{"level":"warn","ts":"2026-07-31T12:45:57.37027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T12:45:57.370353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.134:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.134:2380","--initial-cluster=instance=https://199.19.213.134:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.134:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.134:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-31T12:45:57.370413Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T12:45:57.370422Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-07-31T12:45:57.370538Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T12:45:57.371204Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"]} {"level":"info","ts":"2026-07-31T12:45:57.371341Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.134:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-31T12:45:57.375614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.015585ms"} {"level":"info","ts":"2026-07-31T12:45:57.383765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"92da22d3868c86fb","cluster-id":"84450b28e935eb90"} {"level":"info","ts":"2026-07-31T12:45:57.383855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T12:45:57.383874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 0"} {"level":"info","ts":"2026-07-31T12:45:57.383901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 92da22d3868c86fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T12:45:57.383915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 1"} {"level":"info","ts":"2026-07-31T12:45:57.383961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"warn","ts":"2026-07-31T12:45:57.390009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T12:45:57.391138Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T12:45:57.392511Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-31T12:45:57.394798Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"92da22d3868c86fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T12:45:57.395115Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"92da22d3868c86fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T12:45:57.395201Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:45:57.395394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:45:57.395442Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:45:57.397012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"info","ts":"2026-07-31T12:45:57.397241Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","added-peer-id":"92da22d3868c86fb","added-peer-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-07-31T12:45:57.400513Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T12:45:57.400868Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"92da22d3868c86fb","initial-advertise-peer-urls":["https://199.19.213.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T12:45:57.400929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T12:45:57.401411Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-07-31T12:45:57.401452Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-07-31T12:45:58.184655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T12:45:58.184713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T12:45:58.184737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgPreVoteResp from 92da22d3868c86fb at term 1"} {"level":"info","ts":"2026-07-31T12:45:58.184757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became candidate at term 2"} {"level":"info","ts":"2026-07-31T12:45:58.184772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgVoteResp from 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-07-31T12:45:58.184798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became leader at term 2"} {"level":"info","ts":"2026-07-31T12:45:58.184818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 92da22d3868c86fb elected leader 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-07-31T12:45:58.186035Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"92da22d3868c86fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.134:2379]}","request-path":"/0/members/92da22d3868c86fb/attributes","cluster-id":"84450b28e935eb90","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T12:45:58.186102Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T12:45:58.186256Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:45:58.186259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T12:45:58.186465Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T12:45:58.186525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T12:45:58.187238Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:45:58.18736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:45:58.187394Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:45:58.190168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.134:2379"} {"level":"info","ts":"2026-07-31T12:45:58.19038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-31T12:46:00.938073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761098005,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T12:46:00.939261Z","caller":"traceutil/trace.go:171","msg":"trace[1345882291] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"501.524492ms","start":"2026-07-31T12:46:00.437712Z","end":"2026-07-31T12:46:00.939237Z","steps":["trace[1345882291] 'read index received' (duration: 501.299776ms)","trace[1345882291] 'applied index is now lower than readState.Index' (duration: 223.646µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:00.939437Z","caller":"traceutil/trace.go:171","msg":"trace[1377816636] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"504.293915ms","start":"2026-07-31T12:46:00.435101Z","end":"2026-07-31T12:46:00.939395Z","steps":["trace[1377816636] 'process raft request' (duration: 503.968966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:00.939498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.749598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-31T12:46:00.939652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.264861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T12:46:00.939765Z","caller":"traceutil/trace.go:171","msg":"trace[930961848] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:112; }","duration":"394.403105ms","start":"2026-07-31T12:46:00.545319Z","end":"2026-07-31T12:46:00.939722Z","steps":["trace[930961848] 'agreement among raft nodes before linearized reading' (duration: 394.2274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:00.939599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.511337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-31T12:46:00.939855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.545305Z","time spent":"394.534138ms","remote":"127.0.0.1:46482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-31T12:46:00.940058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.435089Z","time spent":"504.520501ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:46:00.940362Z","caller":"traceutil/trace.go:171","msg":"trace[1700206409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"403.329139ms","start":"2026-07-31T12:46:00.537009Z","end":"2026-07-31T12:46:00.940338Z","steps":["trace[1700206409] 'agreement among raft nodes before linearized reading' (duration: 402.477696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:00.940435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.536994Z","time spent":"403.423541ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:46:00.939845Z","caller":"traceutil/trace.go:171","msg":"trace[1298185211] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"502.030676ms","start":"2026-07-31T12:46:00.437665Z","end":"2026-07-31T12:46:00.939696Z","steps":["trace[1298185211] 'agreement among raft nodes before linearized reading' (duration: 501.704446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:00.940587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.437654Z","time spent":"502.858978ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-07-31T12:46:01.44359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761098009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:46:01.944744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761098009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:46:02.445186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761098009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T12:46:02.455586Z","caller":"traceutil/trace.go:171","msg":"trace[991444999] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"1.512153411s","start":"2026-07-31T12:46:00.943413Z","end":"2026-07-31T12:46:02.455567Z","steps":["trace[991444999] 'read index received' (duration: 1.511934475s)","trace[991444999] 'applied index is now lower than readState.Index' (duration: 217.936µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:02.455787Z","caller":"traceutil/trace.go:171","msg":"trace[1520830481] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"1.512620103s","start":"2026-07-31T12:46:00.94315Z","end":"2026-07-31T12:46:02.455771Z","steps":["trace[1520830481] 'process raft request' (duration: 1.512254954s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.455964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.943127Z","time spent":"1.512735266s","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T12:46:02.455999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512623344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-31T12:46:02.455998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.655229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T12:46:02.456033Z","caller":"traceutil/trace.go:171","msg":"trace[135199461] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:113; }","duration":"1.512691695s","start":"2026-07-31T12:46:00.94333Z","end":"2026-07-31T12:46:02.456022Z","steps":["trace[135199461] 'agreement among raft nodes before linearized reading' (duration: 1.512604532s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:02.456038Z","caller":"traceutil/trace.go:171","msg":"trace[314005770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"918.735301ms","start":"2026-07-31T12:46:01.537293Z","end":"2026-07-31T12:46:02.456028Z","steps":["trace[314005770] 'agreement among raft nodes before linearized reading' (duration: 918.664729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.456063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:01.537272Z","time spent":"918.786852ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:46:02.456063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.943314Z","time spent":"1.512743707s","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-07-31T12:46:02.45617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51211279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T12:46:02.456278Z","caller":"traceutil/trace.go:171","msg":"trace[798020545] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:113; }","duration":"1.512247684s","start":"2026-07-31T12:46:00.944009Z","end":"2026-07-31T12:46:02.456257Z","steps":["trace[798020545] 'agreement among raft nodes before linearized reading' (duration: 1.511922695s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.456365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:00.943998Z","time spent":"1.512352926s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-07-31T12:46:02.949365Z","caller":"traceutil/trace.go:171","msg":"trace[561468184] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"481.7806ms","start":"2026-07-31T12:46:02.467559Z","end":"2026-07-31T12:46:02.94934Z","steps":["trace[561468184] 'read index received' (duration: 473.996235ms)","trace[561468184] 'applied index is now lower than readState.Index' (duration: 7.783175ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:02.949399Z","caller":"traceutil/trace.go:171","msg":"trace[319760062] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"487.722816ms","start":"2026-07-31T12:46:02.46165Z","end":"2026-07-31T12:46:02.949373Z","steps":["trace[319760062] 'process raft request' (duration: 479.997623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.9495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:02.461632Z","time spent":"487.821368ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T12:46:02.949561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.985275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T12:46:02.9496Z","caller":"traceutil/trace.go:171","msg":"trace[1956177973] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:115; }","duration":"482.062167ms","start":"2026-07-31T12:46:02.467528Z","end":"2026-07-31T12:46:02.94959Z","steps":["trace[1956177973] 'agreement among raft nodes before linearized reading' (duration: 481.944174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.949638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.838844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-31T12:46:02.949636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:02.467514Z","time spent":"482.113718ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-07-31T12:46:02.949612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.724826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T12:46:02.949668Z","caller":"traceutil/trace.go:171","msg":"trace[512216000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:115; }","duration":"312.911106ms","start":"2026-07-31T12:46:02.636748Z","end":"2026-07-31T12:46:02.949659Z","steps":["trace[512216000] 'agreement among raft nodes before linearized reading' (duration: 312.854095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.949687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:02.636727Z","time spent":"312.955798ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:46:02.949703Z","caller":"traceutil/trace.go:171","msg":"trace[1857658518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:115; }","duration":"463.86546ms","start":"2026-07-31T12:46:02.485814Z","end":"2026-07-31T12:46:02.949679Z","steps":["trace[1857658518] 'agreement among raft nodes before linearized reading' (duration: 463.709176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:02.949776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:02.485795Z","time spent":"463.964893ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T12:46:37.878486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:37.369517Z","time spent":"508.961733ms","remote":"127.0.0.1:36866","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T12:46:37.878675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.586731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:37.878726Z","caller":"traceutil/trace.go:171","msg":"trace[1276428493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"223.673023ms","start":"2026-07-31T12:46:37.655035Z","end":"2026-07-31T12:46:37.878708Z","steps":["trace[1276428493] 'range keys from in-memory index tree' (duration: 223.500199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:37.878603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.886494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:46:37.878828Z","caller":"traceutil/trace.go:171","msg":"trace[1306285211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"372.149001ms","start":"2026-07-31T12:46:37.506645Z","end":"2026-07-31T12:46:37.878794Z","steps":["trace[1306285211] 'range keys from in-memory index tree' (duration: 371.780951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:37.878849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.05108ms","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:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:37.878917Z","caller":"traceutil/trace.go:171","msg":"trace[479120296] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:530; }","duration":"516.168343ms","start":"2026-07-31T12:46:37.362733Z","end":"2026-07-31T12:46:37.878902Z","steps":["trace[479120296] 'range keys from in-memory index tree' (duration: 515.921026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:37.878913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:37.506628Z","time spent":"372.258404ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:46:37.878955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:37.362719Z","time spent":"516.230025ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-31T12:46:38.012487Z","caller":"traceutil/trace.go:171","msg":"trace[1406647853] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"129.543341ms","start":"2026-07-31T12:46:37.882891Z","end":"2026-07-31T12:46:38.012434Z","steps":["trace[1406647853] 'process raft request' (duration: 129.385397ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:38.012937Z","caller":"traceutil/trace.go:171","msg":"trace[560208558] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"128.237736ms","start":"2026-07-31T12:46:37.884682Z","end":"2026-07-31T12:46:38.01292Z","steps":["trace[560208558] 'process raft request' (duration: 128.120173ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:38.012924Z","caller":"traceutil/trace.go:171","msg":"trace[793215808] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"122.892616ms","start":"2026-07-31T12:46:37.890006Z","end":"2026-07-31T12:46:38.012898Z","steps":["trace[793215808] 'read index received' (duration: 122.238089ms)","trace[793215808] 'applied index is now lower than readState.Index' (duration: 653.767µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:38.013114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.087472ms","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:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:38.013172Z","caller":"traceutil/trace.go:171","msg":"trace[967787975] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:532; }","duration":"123.183735ms","start":"2026-07-31T12:46:37.889972Z","end":"2026-07-31T12:46:38.013156Z","steps":["trace[967787975] 'agreement among raft nodes before linearized reading' (duration: 123.05617ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:38.878254Z","caller":"traceutil/trace.go:171","msg":"trace[1633144194] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"149.189627ms","start":"2026-07-31T12:46:38.72903Z","end":"2026-07-31T12:46:38.87822Z","steps":["trace[1633144194] 'process raft request' (duration: 64.170285ms)","trace[1633144194] 'compare' (duration: 84.792836ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:38.900086Z","caller":"traceutil/trace.go:171","msg":"trace[1009401425] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"141.511745ms","start":"2026-07-31T12:46:38.758552Z","end":"2026-07-31T12:46:38.900064Z","steps":["trace[1009401425] 'process raft request' (duration: 141.420623ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:38.900101Z","caller":"traceutil/trace.go:171","msg":"trace[1371928222] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"153.294595ms","start":"2026-07-31T12:46:38.746764Z","end":"2026-07-31T12:46:38.900058Z","steps":["trace[1371928222] 'process raft request' (duration: 153.016358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.251955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.730802ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-31T12:46:40.25239Z","caller":"traceutil/trace.go:171","msg":"trace[671828350] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.307934051s","start":"2026-07-31T12:46:38.944421Z","end":"2026-07-31T12:46:40.252355Z","steps":["trace[671828350] 'process raft request' (duration: 1.307665014s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:40.252447Z","caller":"traceutil/trace.go:171","msg":"trace[723371498] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.300949028s","start":"2026-07-31T12:46:38.951455Z","end":"2026-07-31T12:46:40.252404Z","steps":["trace[723371498] 'process raft request' (duration: 1.300789304s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.252705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:38.944404Z","time spent":"1.308053555s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:46:40.252738Z","caller":"traceutil/trace.go:171","msg":"trace[762448523] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"1.041808635s","start":"2026-07-31T12:46:39.210909Z","end":"2026-07-31T12:46:40.252717Z","steps":["trace[762448523] 'read index received' (duration: 63.132708ms)","trace[762448523] 'applied index is now lower than readState.Index' (duration: 978.672616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:40.252864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041962268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-31T12:46:40.252889Z","caller":"traceutil/trace.go:171","msg":"trace[1035919166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"1.04204217s","start":"2026-07-31T12:46:39.210838Z","end":"2026-07-31T12:46:40.25288Z","steps":["trace[1035919166] 'agreement among raft nodes before linearized reading' (duration: 1.041984958s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.252793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:38.951444Z","time spent":"1.301116313s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:46:40.25291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:39.210818Z","time spent":"1.042087261s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T12:46:40.253167Z","caller":"traceutil/trace.go:171","msg":"trace[1942599176] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.006573769s","start":"2026-07-31T12:46:39.246572Z","end":"2026-07-31T12:46:40.253146Z","steps":["trace[1942599176] 'process raft request' (duration: 1.006196629s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.253254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006225549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-31T12:46:40.253265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.685905ms","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-07-31T12:46:40.253265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:39.246532Z","time spent":"1.006686422s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:46:40.2533Z","caller":"traceutil/trace.go:171","msg":"trace[902803515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"236.749435ms","start":"2026-07-31T12:46:40.016541Z","end":"2026-07-31T12:46:40.25329Z","steps":["trace[902803515] 'agreement among raft nodes before linearized reading' (duration: 236.597341ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:40.253302Z","caller":"traceutil/trace.go:171","msg":"trace[939557942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"1.006298901s","start":"2026-07-31T12:46:39.246986Z","end":"2026-07-31T12:46:40.253285Z","steps":["trace[939557942] 'agreement among raft nodes before linearized reading' (duration: 1.006146447s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.253343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:39.246971Z","time spent":"1.006364103s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T12:46:40.253598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.066051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:40.253652Z","caller":"traceutil/trace.go:171","msg":"trace[1758680028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"601.192504ms","start":"2026-07-31T12:46:39.652442Z","end":"2026-07-31T12:46:40.253634Z","steps":["trace[1758680028] 'agreement among raft nodes before linearized reading' (duration: 601.082731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:40.253695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:39.652421Z","time spent":"601.262807ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:46:40.253719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.259639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:46:40.253755Z","caller":"traceutil/trace.go:171","msg":"trace[1318280148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"237.31976ms","start":"2026-07-31T12:46:40.016426Z","end":"2026-07-31T12:46:40.253745Z","steps":["trace[1318280148] 'agreement among raft nodes before linearized reading' (duration: 237.227308ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:44.168208Z","caller":"traceutil/trace.go:171","msg":"trace[2141093829] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"250.385784ms","start":"2026-07-31T12:46:43.917796Z","end":"2026-07-31T12:46:44.168182Z","steps":["trace[2141093829] 'process raft request' (duration: 248.802422ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:45.133364Z","caller":"traceutil/trace.go:171","msg":"trace[1935909768] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"507.585377ms","start":"2026-07-31T12:46:44.625749Z","end":"2026-07-31T12:46:45.133335Z","steps":["trace[1935909768] 'process raft request' (duration: 507.350621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:45.133599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:44.625724Z","time spent":"507.743462ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:46:45.172347Z","caller":"traceutil/trace.go:171","msg":"trace[668944034] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"509.703503ms","start":"2026-07-31T12:46:44.662616Z","end":"2026-07-31T12:46:45.172319Z","steps":["trace[668944034] 'process raft request' (duration: 509.190299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:45.172527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:44.662595Z","time spent":"509.846777ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:46:45.976838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.234837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:46:45.976936Z","caller":"traceutil/trace.go:171","msg":"trace[1908046470] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"322.19944ms","start":"2026-07-31T12:46:45.65472Z","end":"2026-07-31T12:46:45.976919Z","steps":["trace[1908046470] 'read index received' (duration: 43.852µs)","trace[1908046470] 'applied index is now lower than readState.Index' (duration: 322.154118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:45.977029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.302033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:45.977052Z","caller":"traceutil/trace.go:171","msg":"trace[1847324554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"322.366634ms","start":"2026-07-31T12:46:45.654677Z","end":"2026-07-31T12:46:45.977044Z","steps":["trace[1847324554] 'agreement among raft nodes before linearized reading' (duration: 322.284922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:45.97708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:45.654655Z","time spent":"322.418845ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:46:45.977377Z","caller":"traceutil/trace.go:171","msg":"trace[56051210] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"746.586712ms","start":"2026-07-31T12:46:45.230759Z","end":"2026-07-31T12:46:45.977345Z","steps":["trace[56051210] 'process raft request' (duration: 187.75888ms)","trace[56051210] 'compare' (duration: 558.016301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:45.977526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:45.230736Z","time spent":"746.723196ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T12:46:46.788976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761099113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T12:46:47.081181Z","caller":"traceutil/trace.go:171","msg":"trace[140766432] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"888.718194ms","start":"2026-07-31T12:46:46.192412Z","end":"2026-07-31T12:46:47.08113Z","steps":["trace[140766432] 'process raft request' (duration: 863.332368ms)","trace[140766432] 'compare' (duration: 24.954745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:47.081358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.192397Z","time spent":"888.875629ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:46:47.086105Z","caller":"traceutil/trace.go:171","msg":"trace[1043097967] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"797.388526ms","start":"2026-07-31T12:46:46.288671Z","end":"2026-07-31T12:46:47.08606Z","steps":["trace[1043097967] 'read index received' (duration: 767.159922ms)","trace[1043097967] 'applied index is now lower than readState.Index' (duration: 30.227454ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:47.086214Z","caller":"traceutil/trace.go:171","msg":"trace[1345329326] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"673.112432ms","start":"2026-07-31T12:46:46.413089Z","end":"2026-07-31T12:46:47.086201Z","steps":["trace[1345329326] 'process raft request' (duration: 672.489206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:47.08631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.413072Z","time spent":"673.171814ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:46:47.086584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.294502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T12:46:47.086633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.234645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:46:47.086672Z","caller":"traceutil/trace.go:171","msg":"trace[944463548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"430.298597ms","start":"2026-07-31T12:46:46.656362Z","end":"2026-07-31T12:46:47.086661Z","steps":["trace[944463548] 'agreement among raft nodes before linearized reading' (duration: 430.237486ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:47.086681Z","caller":"traceutil/trace.go:171","msg":"trace[1300841750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"797.409826ms","start":"2026-07-31T12:46:46.289246Z","end":"2026-07-31T12:46:47.086656Z","steps":["trace[1300841750] 'agreement among raft nodes before linearized reading' (duration: 797.111918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:47.086701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.656346Z","time spent":"430.348198ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:46:47.086726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.28924Z","time spent":"797.476638ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T12:46:47.08697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.994544ms","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-07-31T12:46:47.086992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.31432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:46:47.087005Z","caller":"traceutil/trace.go:171","msg":"trace[2033261163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"795.043385ms","start":"2026-07-31T12:46:46.291952Z","end":"2026-07-31T12:46:47.086995Z","steps":["trace[2033261163] 'agreement among raft nodes before linearized reading' (duration: 794.947422ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:47.08703Z","caller":"traceutil/trace.go:171","msg":"trace[572703558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"798.380042ms","start":"2026-07-31T12:46:46.288639Z","end":"2026-07-31T12:46:47.087019Z","steps":["trace[572703558] 'agreement among raft nodes before linearized reading' (duration: 798.219398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:47.087035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.291946Z","time spent":"795.083036ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:46:47.087062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.288622Z","time spent":"798.432633ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:46:47.08725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.806241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T12:46:47.087282Z","caller":"traceutil/trace.go:171","msg":"trace[946375226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"796.853482ms","start":"2026-07-31T12:46:46.290419Z","end":"2026-07-31T12:46:47.087272Z","steps":["trace[946375226] 'agreement among raft nodes before linearized reading' (duration: 796.717448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:47.08731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:46.290413Z","time spent":"796.891204ms","remote":"127.0.0.1:46610","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":"info","ts":"2026-07-31T12:46:49.127419Z","caller":"traceutil/trace.go:171","msg":"trace[482654354] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"330.291683ms","start":"2026-07-31T12:46:48.797108Z","end":"2026-07-31T12:46:49.1274Z","steps":["trace[482654354] 'process raft request' (duration: 330.17767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:49.127569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:48.79706Z","time spent":"330.452947ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:46:49.133053Z","caller":"traceutil/trace.go:171","msg":"trace[695995606] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"132.625722ms","start":"2026-07-31T12:46:49.000383Z","end":"2026-07-31T12:46:49.133009Z","steps":["trace[695995606] 'process raft request' (duration: 132.526539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:03.724655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761099446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:47:04.224865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761099446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:47:04.503741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.295988939s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T12:47:04.504122Z","caller":"traceutil/trace.go:171","msg":"trace[2019794478] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.296498921s","start":"2026-07-31T12:47:03.207604Z","end":"2026-07-31T12:47:04.504103Z","steps":["trace[2019794478] 'process raft request' (duration: 1.296338037s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.504383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.207422Z","time spent":"1.296890652s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:47:04.541668Z","caller":"traceutil/trace.go:171","msg":"trace[1870358711] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.332040164s","start":"2026-07-31T12:47:03.209604Z","end":"2026-07-31T12:47:04.541645Z","steps":["trace[1870358711] 'process raft request' (duration: 1.331714505s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.541932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.20959Z","time spent":"1.332151977s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:47:04.542136Z","caller":"traceutil/trace.go:171","msg":"trace[1012701410] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.332060495s","start":"2026-07-31T12:47:03.210055Z","end":"2026-07-31T12:47:04.542115Z","steps":["trace[1012701410] 'process raft request' (duration: 1.331541661s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:47:04.54231Z","caller":"traceutil/trace.go:171","msg":"trace[361341116] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.33186905s","start":"2026-07-31T12:47:03.21043Z","end":"2026-07-31T12:47:04.542299Z","steps":["trace[361341116] 'process raft request' (duration: 1.331546631s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.542368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.210418Z","time spent":"1.331922602s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:47:04.542333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.210045Z","time spent":"1.332181469s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:47:04.548656Z","caller":"traceutil/trace.go:171","msg":"trace[1467115775] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:838; }","duration":"1.324195669s","start":"2026-07-31T12:47:03.224443Z","end":"2026-07-31T12:47:04.548639Z","steps":["trace[1467115775] 'read index received' (duration: 1.279551226s)","trace[1467115775] 'applied index is now lower than readState.Index' (duration: 44.643473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:47:04.54882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324367883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:47:04.54885Z","caller":"traceutil/trace.go:171","msg":"trace[2110524485] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:811; }","duration":"1.324429735s","start":"2026-07-31T12:47:03.224413Z","end":"2026-07-31T12:47:04.548843Z","steps":["trace[2110524485] 'agreement among raft nodes before linearized reading' (duration: 1.324347013s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.548895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.224402Z","time spent":"1.324484546s","remote":"127.0.0.1:46648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:47:04.549064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.698039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T12:47:04.549115Z","caller":"traceutil/trace.go:171","msg":"trace[972983031] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:811; }","duration":"908.804632ms","start":"2026-07-31T12:47:03.640298Z","end":"2026-07-31T12:47:04.549103Z","steps":["trace[972983031] 'agreement among raft nodes before linearized reading' (duration: 908.646768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.549144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.640278Z","time spent":"908.859613ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"warn","ts":"2026-07-31T12:47:04.549263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.801258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:47:04.549319Z","caller":"traceutil/trace.go:171","msg":"trace[1009692509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"893.865429ms","start":"2026-07-31T12:47:03.655418Z","end":"2026-07-31T12:47:04.549284Z","steps":["trace[1009692509] 'agreement among raft nodes before linearized reading' (duration: 893.823658ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:47:04.549314Z","caller":"traceutil/trace.go:171","msg":"trace[1692008156] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"812.418242ms","start":"2026-07-31T12:47:03.736258Z","end":"2026-07-31T12:47:04.548677Z","steps":["trace[1692008156] 'process raft request' (duration: 806.033564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:04.549347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.655397Z","time spent":"893.942322ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:47:04.549427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:03.736236Z","time spent":"813.111489ms","remote":"127.0.0.1:46582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:47:38.97175Z","caller":"traceutil/trace.go:171","msg":"trace[1751211784] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"265.239455ms","start":"2026-07-31T12:47:38.706489Z","end":"2026-07-31T12:47:38.971729Z","steps":["trace[1751211784] 'process raft request' (duration: 265.097221ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:47:38.971999Z","caller":"traceutil/trace.go:171","msg":"trace[1012815165] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"265.436981ms","start":"2026-07-31T12:47:38.70654Z","end":"2026-07-31T12:47:38.971977Z","steps":["trace[1012815165] 'process raft request' (duration: 265.155403ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:47:38.972035Z","caller":"traceutil/trace.go:171","msg":"trace[1861787510] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"265.390719ms","start":"2026-07-31T12:47:38.706614Z","end":"2026-07-31T12:47:38.972005Z","steps":["trace[1861787510] 'process raft request' (duration: 265.286697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:40.292104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.791955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:47:40.292168Z","caller":"traceutil/trace.go:171","msg":"trace[871452813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"636.917738ms","start":"2026-07-31T12:47:39.655236Z","end":"2026-07-31T12:47:40.292154Z","steps":["trace[871452813] 'range keys from in-memory index tree' (duration: 636.695942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:40.292205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:39.655212Z","time spent":"636.98654ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:47:40.29219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.48107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:47:40.292276Z","caller":"traceutil/trace.go:171","msg":"trace[1837431150] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1024; }","duration":"409.621564ms","start":"2026-07-31T12:47:39.882636Z","end":"2026-07-31T12:47:40.292257Z","steps":["trace[1837431150] 'count revisions from in-memory index tree' (duration: 409.400479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:40.292322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:39.882627Z","time spent":"409.683215ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:47:40.292481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.41395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:47:40.292721Z","caller":"traceutil/trace.go:171","msg":"trace[1981377066] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1024; }","duration":"984.688678ms","start":"2026-07-31T12:47:39.308014Z","end":"2026-07-31T12:47:40.292702Z","steps":["trace[1981377066] 'count revisions from in-memory index tree' (duration: 984.348189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:40.292787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:39.307971Z","time spent":"984.79675ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:47:40.292895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.596737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:47:40.29297Z","caller":"traceutil/trace.go:171","msg":"trace[1168613875] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1024; }","duration":"449.70764ms","start":"2026-07-31T12:47:39.843252Z","end":"2026-07-31T12:47:40.292959Z","steps":["trace[1168613875] 'count revisions from in-memory index tree' (duration: 449.503485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:40.293013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:39.843231Z","time spent":"449.765782ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:47:41.105059Z","caller":"traceutil/trace.go:171","msg":"trace[473224624] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"379.404654ms","start":"2026-07-31T12:47:40.725628Z","end":"2026-07-31T12:47:41.105033Z","steps":["trace[473224624] 'read index received' (duration: 379.192239ms)","trace[473224624] 'applied index is now lower than readState.Index' (duration: 211.395µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:47:41.105176Z","caller":"traceutil/trace.go:171","msg":"trace[408831952] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"394.395067ms","start":"2026-07-31T12:47:40.710768Z","end":"2026-07-31T12:47:41.105163Z","steps":["trace[408831952] 'process raft request' (duration: 394.071188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.10529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:40.710742Z","time spent":"394.465009ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:47:41.10587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.906497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:47:41.105951Z","caller":"traceutil/trace.go:171","msg":"trace[1302900695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"380.325659ms","start":"2026-07-31T12:47:40.725605Z","end":"2026-07-31T12:47:41.10593Z","steps":["trace[1302900695] 'agreement among raft nodes before linearized reading' (duration: 379.721983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.105996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:40.725593Z","time spent":"380.389591ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:47:41.628959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.220922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:47:41.629284Z","caller":"traceutil/trace.go:171","msg":"trace[1713822671] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"586.169044ms","start":"2026-07-31T12:47:41.043083Z","end":"2026-07-31T12:47:41.629252Z","steps":["trace[1713822671] 'process raft request' (duration: 327.188913ms)","trace[1713822671] 'compare' (duration: 258.122489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:47:41.629444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:41.043062Z","time spent":"586.289766ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:47:41.629455Z","caller":"traceutil/trace.go:171","msg":"trace[2129631721] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"293.572984ms","start":"2026-07-31T12:47:41.335862Z","end":"2026-07-31T12:47:41.629435Z","steps":["trace[2129631721] 'process raft request' (duration: 293.521853ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:47:41.629433Z","caller":"traceutil/trace.go:171","msg":"trace[1520943385] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"524.259727ms","start":"2026-07-31T12:47:41.105131Z","end":"2026-07-31T12:47:41.62939Z","steps":["trace[1520943385] 'read index received' (duration: 265.335998ms)","trace[1520943385] 'applied index is now lower than readState.Index' (duration: 258.919809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:47:41.629534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.374103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:47:41.629591Z","caller":"traceutil/trace.go:171","msg":"trace[1230063290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"654.484967ms","start":"2026-07-31T12:47:40.975091Z","end":"2026-07-31T12:47:41.629576Z","steps":["trace[1230063290] 'agreement among raft nodes before linearized reading' (duration: 654.311643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.629625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:40.975065Z","time spent":"654.551928ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T12:47:41.629651Z","caller":"traceutil/trace.go:171","msg":"trace[2116640852] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"519.432851ms","start":"2026-07-31T12:47:41.110207Z","end":"2026-07-31T12:47:41.62964Z","steps":["trace[2116640852] 'process raft request' (duration: 519.0069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.629731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:41.110194Z","time spent":"519.491144ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:47:41.629743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.683223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T12:47:41.629794Z","caller":"traceutil/trace.go:171","msg":"trace[826342989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"654.777015ms","start":"2026-07-31T12:47:40.974999Z","end":"2026-07-31T12:47:41.629776Z","steps":["trace[826342989] 'agreement among raft nodes before linearized reading' (duration: 654.529128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.629852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:40.974983Z","time spent":"654.856417ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T12:47:41.629886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.831781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T12:47:41.630004Z","caller":"traceutil/trace.go:171","msg":"trace[1282411707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"653.961714ms","start":"2026-07-31T12:47:40.976034Z","end":"2026-07-31T12:47:41.629995Z","steps":["trace[1282411707] 'agreement among raft nodes before linearized reading' (duration: 653.80491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:47:41.630027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:47:40.976026Z","time spent":"653.993804ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T12:48:03.993342Z","caller":"traceutil/trace.go:171","msg":"trace[1617596826] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"338.746284ms","start":"2026-07-31T12:48:03.654567Z","end":"2026-07-31T12:48:03.993314Z","steps":["trace[1617596826] 'read index received' (duration: 338.61431ms)","trace[1617596826] 'applied index is now lower than readState.Index' (duration: 130.544µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:03.993376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.644752Z","time spent":"348.615702ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T12:48:03.993491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.906248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:03.993571Z","caller":"traceutil/trace.go:171","msg":"trace[1553401967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"339.036812ms","start":"2026-07-31T12:48:03.654515Z","end":"2026-07-31T12:48:03.993552Z","steps":["trace[1553401967] 'agreement among raft nodes before linearized reading' (duration: 338.886868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:03.993621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.654491Z","time spent":"339.122023ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:48:04.485879Z","caller":"traceutil/trace.go:171","msg":"trace[650019995] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"780.658331ms","start":"2026-07-31T12:48:03.705191Z","end":"2026-07-31T12:48:04.48585Z","steps":["trace[650019995] 'process raft request' (duration: 766.041429ms)","trace[650019995] 'compare' (duration: 14.394866ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:04.486019Z","caller":"traceutil/trace.go:171","msg":"trace[1465874600] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"491.228245ms","start":"2026-07-31T12:48:03.994775Z","end":"2026-07-31T12:48:04.486003Z","steps":["trace[1465874600] 'process raft request' (duration: 491.064771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.48604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.705159Z","time spent":"780.807545ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:04.485926Z","caller":"traceutil/trace.go:171","msg":"trace[23911188] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"492.489787ms","start":"2026-07-31T12:48:03.993415Z","end":"2026-07-31T12:48:04.485905Z","steps":["trace[23911188] 'read index received' (duration: 477.814314ms)","trace[23911188] 'applied index is now lower than readState.Index' (duration: 14.673973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:04.486287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.970233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:48:04.48632Z","caller":"traceutil/trace.go:171","msg":"trace[1288140765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"639.035365ms","start":"2026-07-31T12:48:03.847276Z","end":"2026-07-31T12:48:04.486311Z","steps":["trace[1288140765] 'agreement among raft nodes before linearized reading' (duration: 638.870129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.48635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.847255Z","time spent":"639.086336ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:04.486354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.994752Z","time spent":"491.324357ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:04.486518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.002198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T12:48:04.48655Z","caller":"traceutil/trace.go:171","msg":"trace[745290406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"643.097291ms","start":"2026-07-31T12:48:03.843445Z","end":"2026-07-31T12:48:04.486542Z","steps":["trace[745290406] 'agreement among raft nodes before linearized reading' (duration: 642.878995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.48662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.912383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:04.48665Z","caller":"traceutil/trace.go:171","msg":"trace[952513439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"491.970554ms","start":"2026-07-31T12:48:03.99467Z","end":"2026-07-31T12:48:04.48664Z","steps":["trace[952513439] 'agreement among raft nodes before linearized reading' (duration: 491.932403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.486665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.994649Z","time spent":"492.012426ms","remote":"127.0.0.1:46358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:48:04.486666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.613643ms","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-07-31T12:48:04.486622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.843424Z","time spent":"643.190992ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T12:48:04.486722Z","caller":"traceutil/trace.go:171","msg":"trace[276218579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"656.704916ms","start":"2026-07-31T12:48:03.830004Z","end":"2026-07-31T12:48:04.486709Z","steps":["trace[276218579] 'agreement among raft nodes before linearized reading' (duration: 656.557962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.487003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.829989Z","time spent":"657.001133ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:48:04.486537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.286751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T12:48:04.487127Z","caller":"traceutil/trace.go:171","msg":"trace[1100768964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"639.921387ms","start":"2026-07-31T12:48:03.847198Z","end":"2026-07-31T12:48:04.487119Z","steps":["trace[1100768964] 'agreement among raft nodes before linearized reading' (duration: 638.750916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.487165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:03.84718Z","time spent":"639.975318ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T12:48:04.950058Z","caller":"traceutil/trace.go:171","msg":"trace[348821884] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"460.545074ms","start":"2026-07-31T12:48:04.489485Z","end":"2026-07-31T12:48:04.95003Z","steps":["trace[348821884] 'read index received' (duration: 415.614921ms)","trace[348821884] 'applied index is now lower than readState.Index' (duration: 44.928643ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:04.950148Z","caller":"traceutil/trace.go:171","msg":"trace[630926512] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"460.859772ms","start":"2026-07-31T12:48:04.489256Z","end":"2026-07-31T12:48:04.950116Z","steps":["trace[630926512] 'process raft request' (duration: 415.882788ms)","trace[630926512] 'compare' (duration: 44.660596ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:04.950342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.489245Z","time spent":"460.974285ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:04.950506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.948214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T12:48:04.950585Z","caller":"traceutil/trace.go:171","msg":"trace[1690802934] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1101; }","duration":"461.093748ms","start":"2026-07-31T12:48:04.489474Z","end":"2026-07-31T12:48:04.950567Z","steps":["trace[1690802934] 'agreement among raft nodes before linearized reading' (duration: 460.687057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.95063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.489469Z","time spent":"461.148509ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-07-31T12:48:04.985303Z","caller":"traceutil/trace.go:171","msg":"trace[1714238194] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"494.603133ms","start":"2026-07-31T12:48:04.490661Z","end":"2026-07-31T12:48:04.985265Z","steps":["trace[1714238194] 'process raft request' (duration: 494.236333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.985468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.490654Z","time spent":"494.736987ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:04.985714Z","caller":"traceutil/trace.go:171","msg":"trace[645662972] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"494.753307ms","start":"2026-07-31T12:48:04.490944Z","end":"2026-07-31T12:48:04.985698Z","steps":["trace[645662972] 'process raft request' (duration: 494.499701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.985746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.648316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:48:04.985788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.490925Z","time spent":"494.82771ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:04.985828Z","caller":"traceutil/trace.go:171","msg":"trace[1883037855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"329.768559ms","start":"2026-07-31T12:48:04.656046Z","end":"2026-07-31T12:48:04.985815Z","steps":["trace[1883037855] 'agreement among raft nodes before linearized reading' (duration: 329.660176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.985895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.655998Z","time spent":"329.886713ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:48:04.985524Z","caller":"traceutil/trace.go:171","msg":"trace[464017727] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"494.644194ms","start":"2026-07-31T12:48:04.490864Z","end":"2026-07-31T12:48:04.985508Z","steps":["trace[464017727] 'process raft request' (duration: 494.241094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:04.986257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:04.49084Z","time spent":"495.341003ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:07.14581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.289644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T12:48:07.14592Z","caller":"traceutil/trace.go:171","msg":"trace[707350144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"155.442919ms","start":"2026-07-31T12:48:06.990452Z","end":"2026-07-31T12:48:07.145895Z","steps":["trace[707350144] 'range keys from in-memory index tree' (duration: 155.1177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:07.145918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.51249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T12:48:07.145959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.529861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:48:07.146026Z","caller":"traceutil/trace.go:171","msg":"trace[198502371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"155.670545ms","start":"2026-07-31T12:48:06.990337Z","end":"2026-07-31T12:48:07.146007Z","steps":["trace[198502371] 'range keys from in-memory index tree' (duration: 155.342366ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:07.146046Z","caller":"traceutil/trace.go:171","msg":"trace[1919991526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"155.662534ms","start":"2026-07-31T12:48:06.990359Z","end":"2026-07-31T12:48:07.146021Z","steps":["trace[1919991526] 'range keys from in-memory index tree' (duration: 155.125411ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:07.539347Z","caller":"traceutil/trace.go:171","msg":"trace[722198604] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"303.494833ms","start":"2026-07-31T12:48:07.235832Z","end":"2026-07-31T12:48:07.539327Z","steps":["trace[722198604] 'read index received' (duration: 288.41201ms)","trace[722198604] 'applied index is now lower than readState.Index' (duration: 15.081803ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:07.539595Z","caller":"traceutil/trace.go:171","msg":"trace[1345718647] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"389.214151ms","start":"2026-07-31T12:48:07.150366Z","end":"2026-07-31T12:48:07.53958Z","steps":["trace[1345718647] 'process raft request' (duration: 373.927992ms)","trace[1345718647] 'compare' (duration: 14.58332ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:07.539696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:07.150354Z","time spent":"389.282002ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:07.539814Z","caller":"traceutil/trace.go:171","msg":"trace[875951741] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"389.345335ms","start":"2026-07-31T12:48:07.15046Z","end":"2026-07-31T12:48:07.539805Z","steps":["trace[875951741] 'process raft request' (duration: 388.78553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:07.539857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:07.150437Z","time spent":"389.395796ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:07.540001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.015472ms","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-07-31T12:48:07.540055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.223893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-31T12:48:07.540066Z","caller":"traceutil/trace.go:171","msg":"trace[1220321272] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1110; }","duration":"282.132306ms","start":"2026-07-31T12:48:07.257918Z","end":"2026-07-31T12:48:07.54005Z","steps":["trace[1220321272] 'agreement among raft nodes before linearized reading' (duration: 281.963851ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:07.540085Z","caller":"traceutil/trace.go:171","msg":"trace[1204950689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"304.281434ms","start":"2026-07-31T12:48:07.235794Z","end":"2026-07-31T12:48:07.540075Z","steps":["trace[1204950689] 'agreement among raft nodes before linearized reading' (duration: 304.181252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:07.540193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:07.235776Z","time spent":"304.335065ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T12:48:24.093525Z","caller":"traceutil/trace.go:171","msg":"trace[940292418] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"482.840091ms","start":"2026-07-31T12:48:23.61066Z","end":"2026-07-31T12:48:24.0935Z","steps":["trace[940292418] 'read index received' (duration: 482.667256ms)","trace[940292418] 'applied index is now lower than readState.Index' (duration: 171.905µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:24.093632Z","caller":"traceutil/trace.go:171","msg":"trace[693124947] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"834.628288ms","start":"2026-07-31T12:48:23.258972Z","end":"2026-07-31T12:48:24.093601Z","steps":["trace[693124947] 'process raft request' (duration: 834.382982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.093757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.081056ms","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-07-31T12:48:24.093783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.258956Z","time spent":"834.740881ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:24.093802Z","caller":"traceutil/trace.go:171","msg":"trace[1877543609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1212; }","duration":"483.163089ms","start":"2026-07-31T12:48:23.610626Z","end":"2026-07-31T12:48:24.093789Z","steps":["trace[1877543609] 'agreement among raft nodes before linearized reading' (duration: 483.021635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.093814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.751312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:48:24.093842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.610611Z","time spent":"483.22225ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T12:48:24.093873Z","caller":"traceutil/trace.go:171","msg":"trace[1982538305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"438.835555ms","start":"2026-07-31T12:48:23.655022Z","end":"2026-07-31T12:48:24.093857Z","steps":["trace[1982538305] 'agreement among raft nodes before linearized reading' (duration: 438.740262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.093909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.655007Z","time spent":"438.895336ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:48:24.093975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.446799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-31T12:48:24.093951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.870525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T12:48:24.094008Z","caller":"traceutil/trace.go:171","msg":"trace[1897593588] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:1212; }","duration":"449.51928ms","start":"2026-07-31T12:48:23.644482Z","end":"2026-07-31T12:48:24.094001Z","steps":["trace[1897593588] 'agreement among raft nodes before linearized reading' (duration: 449.398687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.094013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.405492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-31T12:48:24.09403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.644456Z","time spent":"449.570242ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"info","ts":"2026-07-31T12:48:24.094042Z","caller":"traceutil/trace.go:171","msg":"trace[18819968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1212; }","duration":"431.460684ms","start":"2026-07-31T12:48:23.662573Z","end":"2026-07-31T12:48:24.094033Z","steps":["trace[18819968] 'agreement among raft nodes before linearized reading' (duration: 431.367182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.094257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.662553Z","time spent":"431.69799ms","remote":"127.0.0.1:46610","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":"info","ts":"2026-07-31T12:48:24.094041Z","caller":"traceutil/trace.go:171","msg":"trace[1076418132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1212; }","duration":"482.014818ms","start":"2026-07-31T12:48:23.612007Z","end":"2026-07-31T12:48:24.094022Z","steps":["trace[1076418132] 'agreement among raft nodes before linearized reading' (duration: 481.757701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:24.094449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:23.611991Z","time spent":"482.441979ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:24.596124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:48:25.096563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:48:25.098807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.095255Z","time spent":"1.003547858s","remote":"127.0.0.1:46400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T12:48:25.099681Z","caller":"traceutil/trace.go:171","msg":"trace[373896936] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.001473004s","start":"2026-07-31T12:48:24.098185Z","end":"2026-07-31T12:48:25.099658Z","steps":["trace[373896936] 'process raft request' (duration: 1.00054261s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:25.099791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.098169Z","time spent":"1.001567097s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:25.099777Z","caller":"traceutil/trace.go:171","msg":"trace[371297052] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.001100843s","start":"2026-07-31T12:48:24.098652Z","end":"2026-07-31T12:48:25.099753Z","steps":["trace[371297052] 'process raft request' (duration: 1.00019296s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:25.099975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.098639Z","time spent":"1.001208046s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:25.100488Z","caller":"traceutil/trace.go:171","msg":"trace[609945984] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1264; }","duration":"1.005034175s","start":"2026-07-31T12:48:24.095438Z","end":"2026-07-31T12:48:25.100472Z","steps":["trace[609945984] 'read index received' (duration: 1.001385732s)","trace[609945984] 'applied index is now lower than readState.Index' (duration: 3.647363ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:25.100786Z","caller":"traceutil/trace.go:171","msg":"trace[134620939] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.002044588s","start":"2026-07-31T12:48:24.098719Z","end":"2026-07-31T12:48:25.100764Z","steps":["trace[134620939] 'process raft request' (duration: 1.00096862s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:25.100865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.098712Z","time spent":"1.002112889s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:25.101012Z","caller":"traceutil/trace.go:171","msg":"trace[558199667] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"403.487002ms","start":"2026-07-31T12:48:24.697515Z","end":"2026-07-31T12:48:25.101002Z","steps":["trace[558199667] 'process raft request' (duration: 402.896717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:25.101125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.697496Z","time spent":"403.595025ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:25.101269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005829165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:48:25.101054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.098902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:25.101662Z","caller":"traceutil/trace.go:171","msg":"trace[1416392018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"1.006233967s","start":"2026-07-31T12:48:24.095411Z","end":"2026-07-31T12:48:25.101645Z","steps":["trace[1416392018] 'agreement among raft nodes before linearized reading' (duration: 1.005829766s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:25.101703Z","caller":"traceutil/trace.go:171","msg":"trace[420154974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1217; }","duration":"608.791361ms","start":"2026-07-31T12:48:24.492893Z","end":"2026-07-31T12:48:25.101684Z","steps":["trace[420154974] 'agreement among raft nodes before linearized reading' (duration: 608.080362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:25.101724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.095387Z","time spent":"1.006325149s","remote":"127.0.0.1:46358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:48:25.101749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:24.492875Z","time spent":"608.860473ms","remote":"127.0.0.1:36834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:48:41.726711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100558,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T12:48:41.742527Z","caller":"traceutil/trace.go:171","msg":"trace[2073901582] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"525.563753ms","start":"2026-07-31T12:48:41.216945Z","end":"2026-07-31T12:48:41.742509Z","steps":["trace[2073901582] 'process raft request' (duration: 525.45142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:41.742659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.21692Z","time spent":"525.642175ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:41.743402Z","caller":"traceutil/trace.go:171","msg":"trace[153211142] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1342; }","duration":"517.338481ms","start":"2026-07-31T12:48:41.226048Z","end":"2026-07-31T12:48:41.743386Z","steps":["trace[153211142] 'read index received' (duration: 517.009922ms)","trace[153211142] 'applied index is now lower than readState.Index' (duration: 327.739µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:41.74362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.890383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-31T12:48:41.743745Z","caller":"traceutil/trace.go:171","msg":"trace[1979761817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"510.989656ms","start":"2026-07-31T12:48:41.232695Z","end":"2026-07-31T12:48:41.743685Z","steps":["trace[1979761817] 'agreement among raft nodes before linearized reading' (duration: 510.820412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:41.743793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.232686Z","time spent":"511.094999ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T12:48:41.744009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.959536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T12:48:41.744045Z","caller":"traceutil/trace.go:171","msg":"trace[1439820044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"518.024208ms","start":"2026-07-31T12:48:41.226012Z","end":"2026-07-31T12:48:41.744036Z","steps":["trace[1439820044] 'agreement among raft nodes before linearized reading' (duration: 517.932586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:41.744074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.225999Z","time spent":"518.06801ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:48:41.744432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.054735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-31T12:48:41.744472Z","caller":"traceutil/trace.go:171","msg":"trace[395119067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"509.118267ms","start":"2026-07-31T12:48:41.235345Z","end":"2026-07-31T12:48:41.744463Z","steps":["trace[395119067] 'agreement among raft nodes before linearized reading' (duration: 509.023795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:41.744523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.235336Z","time spent":"509.179788ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:41.744742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.904508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-31T12:48:41.744778Z","caller":"traceutil/trace.go:171","msg":"trace[16153767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"509.95186ms","start":"2026-07-31T12:48:41.234818Z","end":"2026-07-31T12:48:41.74477Z","steps":["trace[16153767] 'agreement among raft nodes before linearized reading' (duration: 509.856757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:41.744806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.234812Z","time spent":"509.987981ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:42.54857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.137416ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:42.5487Z","caller":"traceutil/trace.go:171","msg":"trace[301704238] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1286; }","duration":"550.289731ms","start":"2026-07-31T12:48:41.998389Z","end":"2026-07-31T12:48:42.548678Z","steps":["trace[301704238] 'range keys from in-memory index tree' (duration: 550.109815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:42.549187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.455637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:48:42.549324Z","caller":"traceutil/trace.go:171","msg":"trace[476674356] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"800.519578ms","start":"2026-07-31T12:48:41.74879Z","end":"2026-07-31T12:48:42.549309Z","steps":["trace[476674356] 'process raft request' (duration: 357.866895ms)","trace[476674356] 'compare' (duration: 442.083858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:42.54938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.748754Z","time spent":"800.59854ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:42.549581Z","caller":"traceutil/trace.go:171","msg":"trace[819000414] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"800.637201ms","start":"2026-07-31T12:48:41.748914Z","end":"2026-07-31T12:48:42.549551Z","steps":["trace[819000414] 'process raft request' (duration: 800.356084ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:42.549655Z","caller":"traceutil/trace.go:171","msg":"trace[1311634012] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"800.629071ms","start":"2026-07-31T12:48:41.749002Z","end":"2026-07-31T12:48:42.549631Z","steps":["trace[1311634012] 'process raft request' (duration: 800.471867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:42.549723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.748896Z","time spent":"800.747753ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:42.549762Z","caller":"traceutil/trace.go:171","msg":"trace[743094492] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1342; }","duration":"609.399836ms","start":"2026-07-31T12:48:41.940353Z","end":"2026-07-31T12:48:42.549753Z","steps":["trace[743094492] 'read index received' (duration: 166.2153ms)","trace[743094492] 'applied index is now lower than readState.Index' (duration: 443.183586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:42.549791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.748979Z","time spent":"800.735173ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:42.549816Z","caller":"traceutil/trace.go:171","msg":"trace[1717129547] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"800.744513ms","start":"2026-07-31T12:48:41.749053Z","end":"2026-07-31T12:48:42.549797Z","steps":["trace[1717129547] 'process raft request' (duration: 800.531698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:42.549873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.516459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:42.549897Z","caller":"traceutil/trace.go:171","msg":"trace[42803886] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1290; }","duration":"609.58466ms","start":"2026-07-31T12:48:41.940304Z","end":"2026-07-31T12:48:42.549889Z","steps":["trace[42803886] 'agreement among raft nodes before linearized reading' (duration: 609.542459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:42.549902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.749047Z","time spent":"800.813655ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:42.549919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.940285Z","time spent":"609.628342ms","remote":"127.0.0.1:46582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:48:42.549978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.89735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T12:48:42.549944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.276584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:42.550027Z","caller":"traceutil/trace.go:171","msg":"trace[991023344] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1290; }","duration":"165.980583ms","start":"2026-07-31T12:48:42.38403Z","end":"2026-07-31T12:48:42.550011Z","steps":["trace[991023344] 'agreement among raft nodes before linearized reading' (duration: 165.89674ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:42.550038Z","caller":"traceutil/trace.go:171","msg":"trace[1152656581] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1290; }","duration":"604.401977ms","start":"2026-07-31T12:48:41.94562Z","end":"2026-07-31T12:48:42.550022Z","steps":["trace[1152656581] 'agreement among raft nodes before linearized reading' (duration: 604.261313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:42.550083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:41.945609Z","time spent":"604.458358ms","remote":"127.0.0.1:37986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:48:42.550089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.370653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-31T12:48:42.550143Z","caller":"traceutil/trace.go:171","msg":"trace[2017800267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"297.423085ms","start":"2026-07-31T12:48:42.252707Z","end":"2026-07-31T12:48:42.550131Z","steps":["trace[2017800267] 'agreement among raft nodes before linearized reading' (duration: 297.283171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:44.154809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:48:44.329433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:43.646656Z","time spent":"682.77217ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T12:48:44.607289Z","caller":"traceutil/trace.go:171","msg":"trace[1815383786] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1349; }","duration":"953.133026ms","start":"2026-07-31T12:48:43.654138Z","end":"2026-07-31T12:48:44.607271Z","steps":["trace[1815383786] 'read index received' (duration: 675.141233ms)","trace[1815383786] 'applied index is now lower than readState.Index' (duration: 277.991113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:44.60775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.610939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:44.607786Z","caller":"traceutil/trace.go:171","msg":"trace[1269603721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"953.697061ms","start":"2026-07-31T12:48:43.65408Z","end":"2026-07-31T12:48:44.607777Z","steps":["trace[1269603721] 'agreement among raft nodes before linearized reading' (duration: 953.63217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:44.607809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:43.65406Z","time spent":"953.743863ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T12:48:44.608081Z","caller":"traceutil/trace.go:171","msg":"trace[47746689] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"859.673913ms","start":"2026-07-31T12:48:43.747788Z","end":"2026-07-31T12:48:44.607462Z","steps":["trace[47746689] 'process raft request' (duration: 804.399517ms)","trace[47746689] 'compare' (duration: 54.905847ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:44.608243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:43.747762Z","time spent":"860.390983ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:44.634238Z","caller":"traceutil/trace.go:171","msg":"trace[904012289] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"303.398479ms","start":"2026-07-31T12:48:44.330815Z","end":"2026-07-31T12:48:44.634214Z","steps":["trace[904012289] 'process raft request' (duration: 303.085381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:44.634384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:44.330784Z","time spent":"303.521522ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:44.634374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.722013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:44.634459Z","caller":"traceutil/trace.go:171","msg":"trace[291432015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1295; }","duration":"149.866377ms","start":"2026-07-31T12:48:44.484569Z","end":"2026-07-31T12:48:44.634436Z","steps":["trace[291432015] 'agreement among raft nodes before linearized reading' (duration: 149.724323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:45.002924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.179453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:48:45.003278Z","caller":"traceutil/trace.go:171","msg":"trace[278672758] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"168.552749ms","start":"2026-07-31T12:48:44.834701Z","end":"2026-07-31T12:48:45.003254Z","steps":["trace[278672758] 'process raft request' (duration: 14.982776ms)","trace[278672758] 'compare' (duration: 153.042949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:45.657075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.830761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:48:45.657209Z","caller":"traceutil/trace.go:171","msg":"trace[573025909] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"646.714599ms","start":"2026-07-31T12:48:45.010475Z","end":"2026-07-31T12:48:45.657189Z","steps":["trace[573025909] 'process raft request' (duration: 268.704464ms)","trace[573025909] 'compare' (duration: 377.664646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:45.657287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:45.010456Z","time spent":"646.792041ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:48:45.677963Z","caller":"traceutil/trace.go:171","msg":"trace[1082997718] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"164.29399ms","start":"2026-07-31T12:48:45.513653Z","end":"2026-07-31T12:48:45.677947Z","steps":["trace[1082997718] 'read index received' (duration: 164.111925ms)","trace[1082997718] 'applied index is now lower than readState.Index' (duration: 181.365µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:45.678063Z","caller":"traceutil/trace.go:171","msg":"trace[417208374] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"642.310176ms","start":"2026-07-31T12:48:45.035725Z","end":"2026-07-31T12:48:45.678035Z","steps":["trace[417208374] 'process raft request' (duration: 642.175383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:45.6781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.425383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:45.678125Z","caller":"traceutil/trace.go:171","msg":"trace[1029332991] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1306; }","duration":"164.502315ms","start":"2026-07-31T12:48:45.513616Z","end":"2026-07-31T12:48:45.678118Z","steps":["trace[1029332991] 'agreement among raft nodes before linearized reading' (duration: 164.428373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:45.678191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:45.035711Z","time spent":"642.410268ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:48:45.678258Z","caller":"traceutil/trace.go:171","msg":"trace[862193398] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"644.600254ms","start":"2026-07-31T12:48:45.033597Z","end":"2026-07-31T12:48:45.678197Z","steps":["trace[862193398] 'process raft request' (duration: 644.208414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:45.678459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:45.033573Z","time spent":"644.808549ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:48:54.889435Z","caller":"traceutil/trace.go:171","msg":"trace[372855817] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"294.13793ms","start":"2026-07-31T12:48:54.595274Z","end":"2026-07-31T12:48:54.889412Z","steps":["trace[372855817] 'read index received' (duration: 293.977306ms)","trace[372855817] 'applied index is now lower than readState.Index' (duration: 157.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:54.889533Z","caller":"traceutil/trace.go:171","msg":"trace[677091729] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"420.8739ms","start":"2026-07-31T12:48:54.46864Z","end":"2026-07-31T12:48:54.889514Z","steps":["trace[677091729] 'process raft request' (duration: 420.624124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:54.889572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.274884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:48:54.889604Z","caller":"traceutil/trace.go:171","msg":"trace[417806769] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1371; }","duration":"294.360966ms","start":"2026-07-31T12:48:54.595235Z","end":"2026-07-31T12:48:54.889596Z","steps":["trace[417806769] 'agreement among raft nodes before linearized reading' (duration: 294.283714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:54.889637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.468616Z","time spent":"420.962653ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:55.390061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:48:55.890227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543433761100725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T12:48:56.08448Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1951582s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T12:48:56.284324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.536909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:48:56.284421Z","caller":"traceutil/trace.go:171","msg":"trace[1100865408] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"1.394905675s","start":"2026-07-31T12:48:54.889499Z","end":"2026-07-31T12:48:56.284405Z","steps":["trace[1100865408] 'read index received' (duration: 1.195224041s)","trace[1100865408] 'applied index is now lower than readState.Index' (duration: 199.680184ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:48:56.284642Z","caller":"traceutil/trace.go:171","msg":"trace[2005882809] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.646488557s","start":"2026-07-31T12:48:54.63814Z","end":"2026-07-31T12:48:56.284629Z","steps":["trace[2005882809] 'process raft request' (duration: 1.446569068s)","trace[2005882809] 'compare' (duration: 199.240442ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:56.284703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.638113Z","time spent":"1.646556359s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:48:56.284977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59814509s","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-07-31T12:48:56.284996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.604708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:48:56.284988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161978895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:48:56.285031Z","caller":"traceutil/trace.go:171","msg":"trace[1981631388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"631.67498ms","start":"2026-07-31T12:48:55.653346Z","end":"2026-07-31T12:48:56.285021Z","steps":["trace[1981631388] 'agreement among raft nodes before linearized reading' (duration: 631.617329ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:56.285033Z","caller":"traceutil/trace.go:171","msg":"trace[458420139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1372; }","duration":"1.598233942s","start":"2026-07-31T12:48:54.686784Z","end":"2026-07-31T12:48:56.285018Z","steps":["trace[458420139] 'agreement among raft nodes before linearized reading' (duration: 1.598053458s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.285061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:55.653326Z","time spent":"631.729202ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:48:56.285072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.686769Z","time spent":"1.598295643s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:48:56.285126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.840004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:56.285179Z","caller":"traceutil/trace.go:171","msg":"trace[949992098] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1372; }","duration":"767.897955ms","start":"2026-07-31T12:48:55.517265Z","end":"2026-07-31T12:48:56.285163Z","steps":["trace[949992098] 'agreement among raft nodes before linearized reading' (duration: 767.809523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.28518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57373657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-31T12:48:56.285171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.464973ms","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":"warn","ts":"2026-07-31T12:48:56.285217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:55.517208Z","time spent":"767.999888ms","remote":"127.0.0.1:36920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:48:56.285223Z","caller":"traceutil/trace.go:171","msg":"trace[1162997523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.573788191s","start":"2026-07-31T12:48:54.711426Z","end":"2026-07-31T12:48:56.285214Z","steps":["trace[1162997523] 'agreement among raft nodes before linearized reading' (duration: 1.573672718s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:56.285237Z","caller":"traceutil/trace.go:171","msg":"trace[1160500541] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"586.532654ms","start":"2026-07-31T12:48:55.698689Z","end":"2026-07-31T12:48:56.285222Z","steps":["trace[1160500541] 'agreement among raft nodes before linearized reading' (duration: 586.35394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.285259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.71142Z","time spent":"1.573831742s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:56.285278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:55.69863Z","time spent":"586.639638ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-31T12:48:56.285248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574589501s","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-07-31T12:48:56.285335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581033597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T12:48:56.28536Z","caller":"traceutil/trace.go:171","msg":"trace[2076368564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.574739116s","start":"2026-07-31T12:48:54.710611Z","end":"2026-07-31T12:48:56.28535Z","steps":["trace[2076368564] 'agreement among raft nodes before linearized reading' (duration: 1.574598082s)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:56.285369Z","caller":"traceutil/trace.go:171","msg":"trace[2139539754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"1.581075729s","start":"2026-07-31T12:48:54.704283Z","end":"2026-07-31T12:48:56.285359Z","steps":["trace[2139539754] 'agreement among raft nodes before linearized reading' (duration: 1.580961716s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.285376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.453901ms","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-07-31T12:48:56.285387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.710593Z","time spent":"1.574787117s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:48:56.285396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.704275Z","time spent":"1.58111587s","remote":"127.0.0.1:46610","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":"info","ts":"2026-07-31T12:48:56.285403Z","caller":"traceutil/trace.go:171","msg":"trace[709358881] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"913.504662ms","start":"2026-07-31T12:48:55.371888Z","end":"2026-07-31T12:48:56.285393Z","steps":["trace[709358881] 'agreement among raft nodes before linearized reading' (duration: 913.45078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.285427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:55.37187Z","time spent":"913.551444ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-31T12:48:56.28549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57030549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T12:48:56.285521Z","caller":"traceutil/trace.go:171","msg":"trace[568899163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.570361592s","start":"2026-07-31T12:48:54.71515Z","end":"2026-07-31T12:48:56.285511Z","steps":["trace[568899163] 'agreement among raft nodes before linearized reading' (duration: 1.57028311s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.285549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:54.715127Z","time spent":"1.570413824s","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T12:48:56.285058Z","caller":"traceutil/trace.go:171","msg":"trace[744660180] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1372; }","duration":"1.162101598s","start":"2026-07-31T12:48:55.122939Z","end":"2026-07-31T12:48:56.285041Z","steps":["trace[744660180] 'agreement among raft nodes before linearized reading' (duration: 1.161955584s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.286127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:48:55.122919Z","time spent":"1.163153874s","remote":"127.0.0.1:46520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:53:06.295784Z","caller":"traceutil/trace.go:171","msg":"trace[1094713878] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"214.057416ms","start":"2026-07-31T12:53:06.081704Z","end":"2026-07-31T12:53:06.295762Z","steps":["trace[1094713878] 'process raft request' (duration: 213.901352ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:53:11.811051Z","caller":"traceutil/trace.go:171","msg":"trace[574709776] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"262.13458ms","start":"2026-07-31T12:53:11.5489Z","end":"2026-07-31T12:53:11.811034Z","steps":["trace[574709776] 'process raft request' (duration: 262.020997ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:53:11.848732Z","caller":"traceutil/trace.go:171","msg":"trace[1670083511] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"299.626456ms","start":"2026-07-31T12:53:11.549079Z","end":"2026-07-31T12:53:11.848706Z","steps":["trace[1670083511] 'process raft request' (duration: 299.252716ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:53:11.849136Z","caller":"traceutil/trace.go:171","msg":"trace[1647466903] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"299.851852ms","start":"2026-07-31T12:53:11.54926Z","end":"2026-07-31T12:53:11.849112Z","steps":["trace[1647466903] 'process raft request' (duration: 299.39874ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:53:11.849459Z","caller":"traceutil/trace.go:171","msg":"trace[260067756] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"299.968075ms","start":"2026-07-31T12:53:11.549479Z","end":"2026-07-31T12:53:11.849447Z","steps":["trace[260067756] 'process raft request' (duration: 299.636976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:53:11.849571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:53:11.549473Z","time spent":"300.019096ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:53:11.849468Z","caller":"traceutil/trace.go:171","msg":"trace[287472694] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"299.991515ms","start":"2026-07-31T12:53:11.549432Z","end":"2026-07-31T12:53:11.849423Z","steps":["trace[287472694] 'process raft request' (duration: 299.552884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:53:11.850004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:53:11.549424Z","time spent":"300.446847ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:53:11.973563Z","caller":"traceutil/trace.go:171","msg":"trace[2048232087] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"120.083986ms","start":"2026-07-31T12:53:11.853452Z","end":"2026-07-31T12:53:11.973536Z","steps":["trace[2048232087] 'process raft request' (duration: 50.168688ms)","trace[2048232087] 'compare' (duration: 69.59938ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:53:11.973692Z","caller":"traceutil/trace.go:171","msg":"trace[838480669] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"120.132888ms","start":"2026-07-31T12:53:11.853537Z","end":"2026-07-31T12:53:11.97367Z","steps":["trace[838480669] 'process raft request' (duration: 119.903742ms)"],"step_count":1}