{"level":"warn","ts":"2026-07-23T23:54:54.830916Z","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-23T23:54:54.831023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-23T23:54:54.831083Z","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-23T23:54:54.831091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-23T23:54:54.83113Z","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-23T23:54:54.831986Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-23T23:54:54.832065Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-23T23:54:54.834524Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.21828ms"} {"level":"info","ts":"2026-07-23T23:54:54.842992Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-23T23:54:54.84308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:54:54.843105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-23T23:54:54.843241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:54:54.843253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-23T23:54:54.843301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-23T23:54:54.847939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:54:54.850371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:54:54.852012Z","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-23T23:54:54.854231Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:54:54.854418Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:54:54.854509Z","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-23T23:54:54.856896Z","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-23T23:54:54.856966Z","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-23T23:54:54.855174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-23T23:54:54.857423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-23T23:54:54.870168Z","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-23T23:54:54.870309Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-23T23:54:54.870323Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-23T23:54:54.870931Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:54:54.870954Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:54:55.543617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:54:55.54368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:54:55.543718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-23T23:54:55.543739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:54:55.543756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-23T23:54:55.543777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-23T23:54:55.543796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-23T23:54:55.544776Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:54:55.54498Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:54:55.545025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:54:55.54539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:54:55.545842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:54:55.545969Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:54:55.546006Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:54:55.548057Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-23T23:54:55.549084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:54:55.549133Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:54:55.550456Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:55:04.863889Z","caller":"traceutil/trace.go:171","msg":"trace[1993817657] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"372.2739ms","start":"2026-07-23T23:55:04.491581Z","end":"2026-07-23T23:55:04.863855Z","steps":["trace[1993817657] 'process raft request' (duration: 317.225983ms)","trace[1993817657] 'compare' (duration: 54.82045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:04.864425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:04.491558Z","time spent":"372.425084ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:55:05.221427Z","caller":"traceutil/trace.go:171","msg":"trace[1009098886] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"103.277737ms","start":"2026-07-23T23:55:05.118128Z","end":"2026-07-23T23:55:05.221406Z","steps":["trace[1009098886] 'read index received' (duration: 103.136033ms)","trace[1009098886] 'applied index is now lower than readState.Index' (duration: 140.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:05.221462Z","caller":"traceutil/trace.go:171","msg":"trace[2044099768] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"119.55778ms","start":"2026-07-23T23:55:05.101869Z","end":"2026-07-23T23:55:05.221427Z","steps":["trace[2044099768] 'process raft request' (duration: 119.409706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.221683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.518704ms","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-23T23:55:05.221746Z","caller":"traceutil/trace.go:171","msg":"trace[1046761896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:341; }","duration":"103.652857ms","start":"2026-07-23T23:55:05.118081Z","end":"2026-07-23T23:55:05.221734Z","steps":["trace[1046761896] 'agreement among raft nodes before linearized reading' (duration: 103.482713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.809116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.873324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:55:05.809325Z","caller":"traceutil/trace.go:171","msg":"trace[1052460327] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:353; }","duration":"567.523288ms","start":"2026-07-23T23:55:05.241784Z","end":"2026-07-23T23:55:05.809307Z","steps":["trace[1052460327] 'read index received' (duration: 11.660987ms)","trace[1052460327] 'applied index is now lower than readState.Index' (duration: 555.860671ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:05.809429Z","caller":"traceutil/trace.go:171","msg":"trace[2120388275] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"583.107351ms","start":"2026-07-23T23:55:05.22631Z","end":"2026-07-23T23:55:05.809417Z","steps":["trace[2120388275] 'process raft request' (duration: 329.688332ms)","trace[2120388275] 'compare' (duration: 252.74289ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:05.809493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.22629Z","time spent":"583.175923ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:55:05.809666Z","caller":"traceutil/trace.go:171","msg":"trace[1206852801] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"580.664595ms","start":"2026-07-23T23:55:05.228969Z","end":"2026-07-23T23:55:05.809633Z","steps":["trace[1206852801] 'process raft request' (duration: 580.262854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.809756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.898186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-23T23:55:05.809789Z","caller":"traceutil/trace.go:171","msg":"trace[667016391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"518.955207ms","start":"2026-07-23T23:55:05.290823Z","end":"2026-07-23T23:55:05.809778Z","steps":["trace[667016391] 'agreement among raft nodes before linearized reading' (duration: 518.888255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.809817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.290812Z","time spent":"518.998268ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-23T23:55:05.809925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.808492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-07-23T23:55:05.810006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.017997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-23T23:55:05.81004Z","caller":"traceutil/trace.go:171","msg":"trace[1686553333] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:344; }","duration":"360.115519ms","start":"2026-07-23T23:55:05.449915Z","end":"2026-07-23T23:55:05.81003Z","steps":["trace[1686553333] 'agreement among raft nodes before linearized reading' (duration: 360.018847ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810054Z","caller":"traceutil/trace.go:171","msg":"trace[1625172092] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:344; }","duration":"213.950086ms","start":"2026-07-23T23:55:05.596075Z","end":"2026-07-23T23:55:05.810025Z","steps":["trace[1625172092] 'agreement among raft nodes before linearized reading' (duration: 213.778042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.491007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-23T23:55:05.810082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.228942Z","time spent":"580.800589ms","remote":"127.0.0.1:46622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:05.810143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.274004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-07-23T23:55:05.810164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.621347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-23T23:55:05.810196Z","caller":"traceutil/trace.go:171","msg":"trace[132751028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"419.672628ms","start":"2026-07-23T23:55:05.390516Z","end":"2026-07-23T23:55:05.810188Z","steps":["trace[132751028] 'agreement among raft nodes before linearized reading' (duration: 419.608376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.183252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-23T23:55:05.810229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.745387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-23T23:55:05.810252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.328918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-23T23:55:05.810252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.590077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-23T23:55:05.810262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.801618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-23T23:55:05.810271Z","caller":"traceutil/trace.go:171","msg":"trace[827598571] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"218.829099ms","start":"2026-07-23T23:55:05.591427Z","end":"2026-07-23T23:55:05.810256Z","steps":["trace[827598571] 'agreement among raft nodes before linearized reading' (duration: 218.740276ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810278Z","caller":"traceutil/trace.go:171","msg":"trace[150305067] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"469.37575ms","start":"2026-07-23T23:55:05.340894Z","end":"2026-07-23T23:55:05.81027Z","steps":["trace[150305067] 'agreement among raft nodes before linearized reading' (duration: 469.242986ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810286Z","caller":"traceutil/trace.go:171","msg":"trace[27836803] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"369.683989ms","start":"2026-07-23T23:55:05.44059Z","end":"2026-07-23T23:55:05.810274Z","steps":["trace[27836803] 'agreement among raft nodes before linearized reading' (duration: 369.514034ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810284Z","caller":"traceutil/trace.go:171","msg":"trace[880298586] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:344; }","duration":"214.374447ms","start":"2026-07-23T23:55:05.595876Z","end":"2026-07-23T23:55:05.810251Z","steps":["trace[880298586] 'agreement among raft nodes before linearized reading' (duration: 213.887304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.340846Z","time spent":"469.459112ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-23T23:55:05.810329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.440559Z","time spent":"369.761981ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-23T23:55:05.810428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.939688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-23T23:55:05.810458Z","caller":"traceutil/trace.go:171","msg":"trace[1394218637] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"270.01143ms","start":"2026-07-23T23:55:05.540438Z","end":"2026-07-23T23:55:05.81045Z","steps":["trace[1394218637] 'agreement among raft nodes before linearized reading' (duration: 269.755343ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810137Z","caller":"traceutil/trace.go:171","msg":"trace[929291498] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:344; }","duration":"318.707464ms","start":"2026-07-23T23:55:05.491406Z","end":"2026-07-23T23:55:05.810113Z","steps":["trace[929291498] 'agreement among raft nodes before linearized reading' (duration: 318.416386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.593813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-07-23T23:55:05.810516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.491396Z","time spent":"319.110444ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-23T23:55:05.810539Z","caller":"traceutil/trace.go:171","msg":"trace[797625121] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:344; }","duration":"214.643235ms","start":"2026-07-23T23:55:05.595887Z","end":"2026-07-23T23:55:05.81053Z","steps":["trace[797625121] 'agreement among raft nodes before linearized reading' (duration: 214.580163ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810205Z","caller":"traceutil/trace.go:171","msg":"trace[1942650750] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:344; }","duration":"214.336816ms","start":"2026-07-23T23:55:05.595842Z","end":"2026-07-23T23:55:05.810179Z","steps":["trace[1942650750] 'agreement among raft nodes before linearized reading' (duration: 214.257424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.642685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-23T23:55:05.810298Z","caller":"traceutil/trace.go:171","msg":"trace[1132708611] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"168.85401ms","start":"2026-07-23T23:55:05.641432Z","end":"2026-07-23T23:55:05.810286Z","steps":["trace[1132708611] 'agreement among raft nodes before linearized reading' (duration: 168.794969ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.810615Z","caller":"traceutil/trace.go:171","msg":"trace[747446566] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:344; }","duration":"214.737577ms","start":"2026-07-23T23:55:05.595869Z","end":"2026-07-23T23:55:05.810607Z","steps":["trace[747446566] 'agreement among raft nodes before linearized reading' (duration: 214.661775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.4499Z","time spent":"360.175381ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-23T23:55:05.810031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.338787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-23T23:55:05.810673Z","caller":"traceutil/trace.go:171","msg":"trace[187366407] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:344; }","duration":"215.082167ms","start":"2026-07-23T23:55:05.595581Z","end":"2026-07-23T23:55:05.810663Z","steps":["trace[187366407] 'agreement among raft nodes before linearized reading' (duration: 214.266134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.810223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.390505Z","time spent":"419.710409ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-23T23:55:05.810834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.027648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-23T23:55:05.811095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.147999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-23T23:55:05.811153Z","caller":"traceutil/trace.go:171","msg":"trace[767760074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"119.519039ms","start":"2026-07-23T23:55:05.691621Z","end":"2026-07-23T23:55:05.81114Z","steps":["trace[767760074] 'agreement among raft nodes before linearized reading' (duration: 119.141388ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:05.81136Z","caller":"traceutil/trace.go:171","msg":"trace[1031582812] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"569.132521ms","start":"2026-07-23T23:55:05.241733Z","end":"2026-07-23T23:55:05.810866Z","steps":["trace[1031582812] 'agreement among raft nodes before linearized reading' (duration: 569.033498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:05.811411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:05.24172Z","time spent":"569.676577ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-07-23T23:55:06.084802Z","caller":"traceutil/trace.go:171","msg":"trace[1067392583] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"264.363476ms","start":"2026-07-23T23:55:05.820376Z","end":"2026-07-23T23:55:06.084739Z","steps":["trace[1067392583] 'process raft request' (duration: 238.7472ms)","trace[1067392583] 'compare' (duration: 24.066044ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:06.085266Z","caller":"traceutil/trace.go:171","msg":"trace[220417259] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"262.709852ms","start":"2026-07-23T23:55:05.822204Z","end":"2026-07-23T23:55:06.084914Z","steps":["trace[220417259] 'process raft request' (duration: 262.130046ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:06.086917Z","caller":"traceutil/trace.go:171","msg":"trace[310325337] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"264.525741ms","start":"2026-07-23T23:55:05.822347Z","end":"2026-07-23T23:55:06.086873Z","steps":["trace[310325337] 'process raft request' (duration: 262.129146ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:06.087164Z","caller":"traceutil/trace.go:171","msg":"trace[1050420517] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:357; }","duration":"245.414031ms","start":"2026-07-23T23:55:05.841607Z","end":"2026-07-23T23:55:06.087021Z","steps":["trace[1050420517] 'read index received' (duration: 31.200698ms)","trace[1050420517] 'applied index is now lower than readState.Index' (duration: 214.211443ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:06.090451Z","caller":"traceutil/trace.go:171","msg":"trace[315827710] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"264.061809ms","start":"2026-07-23T23:55:05.825512Z","end":"2026-07-23T23:55:06.089574Z","steps":["trace[315827710] 'process raft request' (duration: 260.904583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.09193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.255324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-23T23:55:06.091973Z","caller":"traceutil/trace.go:171","msg":"trace[201588756] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"200.328495ms","start":"2026-07-23T23:55:05.891633Z","end":"2026-07-23T23:55:06.091962Z","steps":["trace[201588756] 'agreement among raft nodes before linearized reading' (duration: 198.009542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.092036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.011156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-23T23:55:06.092077Z","caller":"traceutil/trace.go:171","msg":"trace[2109490261] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:350; }","duration":"101.110358ms","start":"2026-07-23T23:55:05.990956Z","end":"2026-07-23T23:55:06.092067Z","steps":["trace[2109490261] 'agreement among raft nodes before linearized reading' (duration: 98.694523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.092138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.51641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-23T23:55:06.092203Z","caller":"traceutil/trace.go:171","msg":"trace[640029381] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"250.611373ms","start":"2026-07-23T23:55:05.841576Z","end":"2026-07-23T23:55:06.092187Z","steps":["trace[640029381] 'agreement among raft nodes before linearized reading' (duration: 247.538389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:06.092215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.316506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-23T23:55:06.092238Z","caller":"traceutil/trace.go:171","msg":"trace[1626383065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:350; }","duration":"150.380638ms","start":"2026-07-23T23:55:05.941851Z","end":"2026-07-23T23:55:06.092231Z","steps":["trace[1626383065] 'agreement among raft nodes before linearized reading' (duration: 147.802458ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:06.092932Z","caller":"traceutil/trace.go:171","msg":"trace[947728199] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"263.677177ms","start":"2026-07-23T23:55:05.826005Z","end":"2026-07-23T23:55:06.089682Z","steps":["trace[947728199] 'process raft request' (duration: 260.772489ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:25.471926Z","caller":"traceutil/trace.go:171","msg":"trace[77711147] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"129.447839ms","start":"2026-07-23T23:55:25.342417Z","end":"2026-07-23T23:55:25.471865Z","steps":["trace[77711147] 'process raft request' (duration: 129.254594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:25.966843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.138257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:25.966987Z","caller":"traceutil/trace.go:171","msg":"trace[1573265872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"233.365024ms","start":"2026-07-23T23:55:25.733592Z","end":"2026-07-23T23:55:25.966957Z","steps":["trace[1573265872] 'range keys from in-memory index tree' (duration: 233.043145ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.469725Z","caller":"traceutil/trace.go:171","msg":"trace[167849304] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"120.000802ms","start":"2026-07-23T23:55:30.349694Z","end":"2026-07-23T23:55:30.469695Z","steps":["trace[167849304] 'process raft request' (duration: 119.658473ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.861959Z","caller":"traceutil/trace.go:171","msg":"trace[83811257] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"128.454663ms","start":"2026-07-23T23:55:30.73347Z","end":"2026-07-23T23:55:30.861925Z","steps":["trace[83811257] 'read index received' (duration: 128.208036ms)","trace[83811257] 'applied index is now lower than readState.Index' (duration: 245.997µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:30.862149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.653408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:30.862195Z","caller":"traceutil/trace.go:171","msg":"trace[1583194207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"128.760691ms","start":"2026-07-23T23:55:30.73342Z","end":"2026-07-23T23:55:30.862181Z","steps":["trace[1583194207] 'agreement among raft nodes before linearized reading' (duration: 128.633147ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:30.862358Z","caller":"traceutil/trace.go:171","msg":"trace[1230908064] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"134.209078ms","start":"2026-07-23T23:55:30.728108Z","end":"2026-07-23T23:55:30.862318Z","steps":["trace[1230908064] 'process raft request' (duration: 133.631143ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:34.621274Z","caller":"traceutil/trace.go:171","msg":"trace[247164565] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"101.303324ms","start":"2026-07-23T23:55:34.519936Z","end":"2026-07-23T23:55:34.621239Z","steps":["trace[247164565] 'process raft request' (duration: 61.266335ms)","trace[247164565] 'compare' (duration: 39.699339ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:35.082621Z","caller":"traceutil/trace.go:171","msg":"trace[766254887] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"118.09034ms","start":"2026-07-23T23:55:34.964503Z","end":"2026-07-23T23:55:35.082594Z","steps":["trace[766254887] 'process raft request' (duration: 117.892605ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.082703Z","caller":"traceutil/trace.go:171","msg":"trace[1820468933] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"116.953329ms","start":"2026-07-23T23:55:34.965726Z","end":"2026-07-23T23:55:35.082679Z","steps":["trace[1820468933] 'process raft request' (duration: 116.712563ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.083076Z","caller":"traceutil/trace.go:171","msg":"trace[1225994965] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"112.439307ms","start":"2026-07-23T23:55:34.970617Z","end":"2026-07-23T23:55:35.083056Z","steps":["trace[1225994965] 'process raft request' (duration: 111.924593ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.083117Z","caller":"traceutil/trace.go:171","msg":"trace[1074890457] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"116.783075ms","start":"2026-07-23T23:55:34.966317Z","end":"2026-07-23T23:55:35.0831Z","steps":["trace[1074890457] 'process raft request' (duration: 116.174928ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.08251Z","caller":"traceutil/trace.go:171","msg":"trace[970687036] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"118.893472ms","start":"2026-07-23T23:55:34.963572Z","end":"2026-07-23T23:55:35.082465Z","steps":["trace[970687036] 'process raft request' (duration: 87.983322ms)","trace[970687036] 'compare' (duration: 30.688644ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:35.294891Z","caller":"traceutil/trace.go:171","msg":"trace[91476597] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"103.851084ms","start":"2026-07-23T23:55:35.191009Z","end":"2026-07-23T23:55:35.29486Z","steps":["trace[91476597] 'process raft request' (duration: 55.183421ms)","trace[91476597] 'compare' (duration: 48.482928ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:35.322325Z","caller":"traceutil/trace.go:171","msg":"trace[266620576] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"120.263919ms","start":"2026-07-23T23:55:35.202033Z","end":"2026-07-23T23:55:35.322297Z","steps":["trace[266620576] 'read index received' (duration: 44.174391ms)","trace[266620576] 'applied index is now lower than readState.Index' (duration: 76.088238ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:35.322485Z","caller":"traceutil/trace.go:171","msg":"trace[41656491] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"130.670462ms","start":"2026-07-23T23:55:35.191802Z","end":"2026-07-23T23:55:35.322472Z","steps":["trace[41656491] 'process raft request' (duration: 130.248241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.322798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.722372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:35.322925Z","caller":"traceutil/trace.go:171","msg":"trace[1715106143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:564; }","duration":"120.903607ms","start":"2026-07-23T23:55:35.202Z","end":"2026-07-23T23:55:35.322904Z","steps":["trace[1715106143] 'agreement among raft nodes before linearized reading' (duration: 120.691821ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:35.32258Z","caller":"traceutil/trace.go:171","msg":"trace[89967712] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"111.287345ms","start":"2026-07-23T23:55:35.211241Z","end":"2026-07-23T23:55:35.322529Z","steps":["trace[89967712] 'process raft request' (duration: 110.965216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.333118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.990151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:35.333217Z","caller":"traceutil/trace.go:171","msg":"trace[81279829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:565; }","duration":"131.185506ms","start":"2026-07-23T23:55:35.202012Z","end":"2026-07-23T23:55:35.333198Z","steps":["trace[81279829] 'agreement among raft nodes before linearized reading' (duration: 131.004831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:35.333415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.169956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:35.333607Z","caller":"traceutil/trace.go:171","msg":"trace[1170363830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:565; }","duration":"131.388552ms","start":"2026-07-23T23:55:35.202208Z","end":"2026-07-23T23:55:35.333597Z","steps":["trace[1170363830] 'agreement among raft nodes before linearized reading' (duration: 131.142425ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:36.064855Z","caller":"traceutil/trace.go:171","msg":"trace[285660319] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"113.418723ms","start":"2026-07-23T23:55:35.951359Z","end":"2026-07-23T23:55:36.064777Z","steps":["trace[285660319] 'read index received' (duration: 105.882718ms)","trace[285660319] 'applied index is now lower than readState.Index' (duration: 7.534195ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:36.064902Z","caller":"traceutil/trace.go:171","msg":"trace[1738378450] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"128.900574ms","start":"2026-07-23T23:55:35.935958Z","end":"2026-07-23T23:55:36.064858Z","steps":["trace[1738378450] 'process raft request' (duration: 127.382943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.065139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.711822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:36.065239Z","caller":"traceutil/trace.go:171","msg":"trace[975259134] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:595; }","duration":"113.892206ms","start":"2026-07-23T23:55:35.951329Z","end":"2026-07-23T23:55:36.065221Z","steps":["trace[975259134] 'agreement among raft nodes before linearized reading' (duration: 113.617619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.066433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.901994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:36.066499Z","caller":"traceutil/trace.go:171","msg":"trace[381865799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:596; }","duration":"115.006517ms","start":"2026-07-23T23:55:35.951478Z","end":"2026-07-23T23:55:36.066485Z","steps":["trace[381865799] 'agreement among raft nodes before linearized reading' (duration: 114.869553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.068394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.855527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:36.068441Z","caller":"traceutil/trace.go:171","msg":"trace[1571044076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:596; }","duration":"116.914528ms","start":"2026-07-23T23:55:35.951516Z","end":"2026-07-23T23:55:36.06843Z","steps":["trace[1571044076] 'agreement among raft nodes before linearized reading' (duration: 116.853037ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:36.694186Z","caller":"traceutil/trace.go:171","msg":"trace[1613704870] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"602.645581ms","start":"2026-07-23T23:55:36.091523Z","end":"2026-07-23T23:55:36.694169Z","steps":["trace[1613704870] 'process raft request' (duration: 596.615767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.694299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:36.091501Z","time spent":"602.735694ms","remote":"127.0.0.1:39344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:36.699659Z","caller":"traceutil/trace.go:171","msg":"trace[1538661747] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"534.833518ms","start":"2026-07-23T23:55:36.164803Z","end":"2026-07-23T23:55:36.699637Z","steps":["trace[1538661747] 'process raft request' (duration: 534.340274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.699835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:36.164786Z","time spent":"534.93358ms","remote":"127.0.0.1:39344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:55:36.700802Z","caller":"traceutil/trace.go:171","msg":"trace[2012429928] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"431.982761ms","start":"2026-07-23T23:55:36.268781Z","end":"2026-07-23T23:55:36.700763Z","steps":["trace[2012429928] 'process raft request' (duration: 431.123558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:36.700942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:36.268757Z","time spent":"432.113705ms","remote":"127.0.0.1:39344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:42.949462Z","caller":"traceutil/trace.go:171","msg":"trace[1384829025] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"213.527464ms","start":"2026-07-23T23:55:42.735902Z","end":"2026-07-23T23:55:42.94943Z","steps":["trace[1384829025] 'process raft request' (duration: 213.271287ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:43.055144Z","caller":"traceutil/trace.go:171","msg":"trace[1168543784] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"318.733095ms","start":"2026-07-23T23:55:42.73636Z","end":"2026-07-23T23:55:43.055093Z","steps":["trace[1168543784] 'process raft request' (duration: 234.336981ms)","trace[1168543784] 'compare' (duration: 84.221319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:43.05537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:42.736335Z","time spent":"318.908179ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:43.782035Z","caller":"traceutil/trace.go:171","msg":"trace[543649268] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"583.992664ms","start":"2026-07-23T23:55:43.198018Z","end":"2026-07-23T23:55:43.782011Z","steps":["trace[543649268] 'process raft request' (duration: 583.859731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:43.782177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:43.197978Z","time spent":"584.123698ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:44.481888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.7442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:55:44.481997Z","caller":"traceutil/trace.go:171","msg":"trace[1436722791] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"1.069375869s","start":"2026-07-23T23:55:43.412603Z","end":"2026-07-23T23:55:44.481979Z","steps":["trace[1436722791] 'read index received' (duration: 369.3755ms)","trace[1436722791] 'applied index is now lower than readState.Index' (duration: 699.998649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:44.482195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069586405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9glpq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-23T23:55:44.482205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.928052ms","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-23T23:55:44.482233Z","caller":"traceutil/trace.go:171","msg":"trace[1818870418] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9glpq; range_end:; response_count:1; response_revision:653; }","duration":"1.069631816s","start":"2026-07-23T23:55:43.412585Z","end":"2026-07-23T23:55:44.482217Z","steps":["trace[1818870418] 'agreement among raft nodes before linearized reading' (duration: 1.069471742s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:44.482259Z","caller":"traceutil/trace.go:171","msg":"trace[1100514458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"879.021065ms","start":"2026-07-23T23:55:43.603221Z","end":"2026-07-23T23:55:44.482242Z","steps":["trace[1100514458] 'agreement among raft nodes before linearized reading' (duration: 878.86702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:44.482274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:43.412451Z","time spent":"1.069814011s","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-9glpq\" "} {"level":"warn","ts":"2026-07-23T23:55:44.482301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:43.603202Z","time spent":"879.091977ms","remote":"127.0.0.1:46438","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-23T23:55:44.482532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.34055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:44.48257Z","caller":"traceutil/trace.go:171","msg":"trace[269081763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"749.430192ms","start":"2026-07-23T23:55:43.73313Z","end":"2026-07-23T23:55:44.48256Z","steps":["trace[269081763] 'agreement among raft nodes before linearized reading' (duration: 749.376671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:44.482596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:43.733101Z","time spent":"749.489664ms","remote":"127.0.0.1:39284","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-23T23:55:46.364904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519783944s","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-23T23:55:46.365049Z","caller":"traceutil/trace.go:171","msg":"trace[2038704553] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:654; }","duration":"1.519944348s","start":"2026-07-23T23:55:44.845083Z","end":"2026-07-23T23:55:46.365027Z","steps":["trace[2038704553] 'range keys from in-memory index tree' (duration: 1.519767733s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:46.365261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779865183s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:55:46.365334Z","caller":"traceutil/trace.go:171","msg":"trace[716059984] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"1.631525341s","start":"2026-07-23T23:55:44.733796Z","end":"2026-07-23T23:55:46.365322Z","steps":["trace[716059984] 'read index received' (duration: 98.672µs)","trace[716059984] 'applied index is now lower than readState.Index' (duration: 1.631425649s)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:46.365477Z","caller":"traceutil/trace.go:171","msg":"trace[1098834648] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.866856498s","start":"2026-07-23T23:55:44.498561Z","end":"2026-07-23T23:55:46.365418Z","steps":["trace[1098834648] 'process raft request' (duration: 86.767289ms)","trace[1098834648] 'compare' (duration: 1.779542185s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:46.365553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:44.498535Z","time spent":"1.866974022s","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:55:46.365596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410862452s","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-23T23:55:46.365643Z","caller":"traceutil/trace.go:171","msg":"trace[1738880957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.410949675s","start":"2026-07-23T23:55:44.954682Z","end":"2026-07-23T23:55:46.365632Z","steps":["trace[1738880957] 'agreement among raft nodes before linearized reading' (duration: 1.410817102s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:46.36568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:44.954656Z","time spent":"1.411016496s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:55:46.365818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305617171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:55:46.36586Z","caller":"traceutil/trace.go:171","msg":"trace[1394119044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.305689173s","start":"2026-07-23T23:55:45.060158Z","end":"2026-07-23T23:55:46.365847Z","steps":["trace[1394119044] 'agreement among raft nodes before linearized reading' (duration: 1.30558836s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:46.365889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:45.060132Z","time spent":"1.305750275s","remote":"127.0.0.1:46438","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-23T23:55:46.365863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632062186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:46.365941Z","caller":"traceutil/trace.go:171","msg":"trace[2035622446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.632194549s","start":"2026-07-23T23:55:44.73373Z","end":"2026-07-23T23:55:46.365924Z","steps":["trace[2035622446] 'agreement among raft nodes before linearized reading' (duration: 1.632063416s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:46.365928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.964279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T23:55:46.366002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:44.73369Z","time spent":"1.632301332s","remote":"127.0.0.1:39284","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-23T23:55:46.366014Z","caller":"traceutil/trace.go:171","msg":"trace[1331664993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"579.094022ms","start":"2026-07-23T23:55:45.786896Z","end":"2026-07-23T23:55:46.36599Z","steps":["trace[1331664993] 'agreement among raft nodes before linearized reading' (duration: 578.855596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:46.366056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:45.786872Z","time spent":"579.175295ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:55:46.489215Z","caller":"traceutil/trace.go:171","msg":"trace[800401078] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"118.389186ms","start":"2026-07-23T23:55:46.370795Z","end":"2026-07-23T23:55:46.489184Z","steps":["trace[800401078] 'process raft request' (duration: 105.079064ms)","trace[800401078] 'compare' (duration: 13.164668ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:46.49087Z","caller":"traceutil/trace.go:171","msg":"trace[795416662] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"115.890168ms","start":"2026-07-23T23:55:46.374956Z","end":"2026-07-23T23:55:46.490846Z","steps":["trace[795416662] 'read index received' (duration: 100.932602ms)","trace[795416662] 'applied index is now lower than readState.Index' (duration: 14.956306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:46.491034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.063372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:46.491106Z","caller":"traceutil/trace.go:171","msg":"trace[425753530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"116.178556ms","start":"2026-07-23T23:55:46.374914Z","end":"2026-07-23T23:55:46.491093Z","steps":["trace[425753530] 'agreement among raft nodes before linearized reading' (duration: 116.039832ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:46.491442Z","caller":"traceutil/trace.go:171","msg":"trace[936206971] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"120.490332ms","start":"2026-07-23T23:55:46.370887Z","end":"2026-07-23T23:55:46.491378Z","steps":["trace[936206971] 'process raft request' (duration: 119.791313ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:46.492924Z","caller":"traceutil/trace.go:171","msg":"trace[1874368863] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"121.755388ms","start":"2026-07-23T23:55:46.371141Z","end":"2026-07-23T23:55:46.492897Z","steps":["trace[1874368863] 'process raft request' (duration: 119.658871ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:47.962526Z","caller":"traceutil/trace.go:171","msg":"trace[236174767] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"229.202585ms","start":"2026-07-23T23:55:47.733283Z","end":"2026-07-23T23:55:47.962486Z","steps":["trace[236174767] 'read index received' (duration: 189.636041ms)","trace[236174767] 'applied index is now lower than readState.Index' (duration: 39.564504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:47.962742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.433832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:47.962719Z","caller":"traceutil/trace.go:171","msg":"trace[631092951] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"383.062534ms","start":"2026-07-23T23:55:47.57963Z","end":"2026-07-23T23:55:47.962693Z","steps":["trace[631092951] 'process raft request' (duration: 343.213271ms)","trace[631092951] 'compare' (duration: 39.37166ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:47.962786Z","caller":"traceutil/trace.go:171","msg":"trace[1675090806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"229.542145ms","start":"2026-07-23T23:55:47.733228Z","end":"2026-07-23T23:55:47.96277Z","steps":["trace[1675090806] 'agreement among raft nodes before linearized reading' (duration: 229.391571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:47.962875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:55:47.579606Z","time spent":"383.183207ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:55:59.735539Z","caller":"traceutil/trace.go:171","msg":"trace[844422576] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"183.965886ms","start":"2026-07-23T23:55:59.55154Z","end":"2026-07-23T23:55:59.735506Z","steps":["trace[844422576] 'process raft request' (duration: 182.378343ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:02.492843Z","caller":"traceutil/trace.go:171","msg":"trace[157983837] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"280.55061ms","start":"2026-07-23T23:56:02.212273Z","end":"2026-07-23T23:56:02.492824Z","steps":["trace[157983837] 'process raft request' (duration: 280.427766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:47.003045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.348129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:56:47.003136Z","caller":"traceutil/trace.go:171","msg":"trace[1137630229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1062; }","duration":"120.491123ms","start":"2026-07-23T23:56:46.882625Z","end":"2026-07-23T23:56:47.003116Z","steps":["trace[1137630229] 'count revisions from in-memory index tree' (duration: 120.252637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:47.003214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.726123ms","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-23T23:56:47.00326Z","caller":"traceutil/trace.go:171","msg":"trace[63682671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"122.809196ms","start":"2026-07-23T23:56:46.88044Z","end":"2026-07-23T23:56:47.003249Z","steps":["trace[63682671] 'range keys from in-memory index tree' (duration: 122.60159ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:47.131733Z","caller":"traceutil/trace.go:171","msg":"trace[1011619568] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"124.017868ms","start":"2026-07-23T23:56:47.007695Z","end":"2026-07-23T23:56:47.131713Z","steps":["trace[1011619568] 'process raft request' (duration: 123.965907ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:47.131773Z","caller":"traceutil/trace.go:171","msg":"trace[1165954020] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"124.201793ms","start":"2026-07-23T23:56:47.007542Z","end":"2026-07-23T23:56:47.131744Z","steps":["trace[1165954020] 'process raft request' (duration: 123.962867ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:47.131875Z","caller":"traceutil/trace.go:171","msg":"trace[1325663435] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"122.968759ms","start":"2026-07-23T23:56:47.008897Z","end":"2026-07-23T23:56:47.131866Z","steps":["trace[1325663435] 'process raft request' (duration: 122.787974ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:52.940159Z","caller":"traceutil/trace.go:171","msg":"trace[1699703832] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"208.791442ms","start":"2026-07-23T23:56:52.731346Z","end":"2026-07-23T23:56:52.940138Z","steps":["trace[1699703832] 'read index received' (duration: 208.629657ms)","trace[1699703832] 'applied index is now lower than readState.Index' (duration: 160.895µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:56:52.940289Z","caller":"traceutil/trace.go:171","msg":"trace[1996339905] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"317.969404ms","start":"2026-07-23T23:56:52.622281Z","end":"2026-07-23T23:56:52.94025Z","steps":["trace[1996339905] 'process raft request' (duration: 317.750938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:52.940323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.950056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:56:52.940361Z","caller":"traceutil/trace.go:171","msg":"trace[239594033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"209.047089ms","start":"2026-07-23T23:56:52.731304Z","end":"2026-07-23T23:56:52.940351Z","steps":["trace[239594033] 'agreement among raft nodes before linearized reading' (duration: 208.944056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:52.940396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.083051ms","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-23T23:56:52.940447Z","caller":"traceutil/trace.go:171","msg":"trace[1130151402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"196.157214ms","start":"2026-07-23T23:56:52.744278Z","end":"2026-07-23T23:56:52.940435Z","steps":["trace[1130151402] 'agreement among raft nodes before linearized reading' (duration: 196.00865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:52.940453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:52.622244Z","time spent":"318.125798ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:56:53.111481Z","caller":"traceutil/trace.go:171","msg":"trace[4263092] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"167.583419ms","start":"2026-07-23T23:56:52.94386Z","end":"2026-07-23T23:56:53.111443Z","steps":["trace[4263092] 'process raft request' (duration: 85.593304ms)","trace[4263092] 'compare' (duration: 81.844761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:56:59.636298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.958941ms","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-23T23:56:59.636483Z","caller":"traceutil/trace.go:171","msg":"trace[887490091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"415.179876ms","start":"2026-07-23T23:56:59.221287Z","end":"2026-07-23T23:56:59.636466Z","steps":["trace[887490091] 'range keys from in-memory index tree' (duration: 414.820366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.636521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.221267Z","time spent":"415.241858ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:56:59.636999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.607196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:56:59.637053Z","caller":"traceutil/trace.go:171","msg":"trace[1538222537] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"368.944145ms","start":"2026-07-23T23:56:59.268101Z","end":"2026-07-23T23:56:59.637045Z","steps":["trace[1538222537] 'read index received' (duration: 220.284747ms)","trace[1538222537] 'applied index is now lower than readState.Index' (duration: 148.658328ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:56:59.637136Z","caller":"traceutil/trace.go:171","msg":"trace[1462927171] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"388.302358ms","start":"2026-07-23T23:56:59.248826Z","end":"2026-07-23T23:56:59.637128Z","steps":["trace[1462927171] 'process raft request' (duration: 239.506426ms)","trace[1462927171] 'compare' (duration: 148.484043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:56:59.637177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.248814Z","time spent":"388.343819ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:56:59.637348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.789791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T23:56:59.637381Z","caller":"traceutil/trace.go:171","msg":"trace[1457857382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"368.849402ms","start":"2026-07-23T23:56:59.268521Z","end":"2026-07-23T23:56:59.637371Z","steps":["trace[1457857382] 'agreement among raft nodes before linearized reading' (duration: 368.76483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.637399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.268507Z","time spent":"368.887984ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:56:59.637471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.152489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:56:59.6375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.399217ms","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-23T23:56:59.63754Z","caller":"traceutil/trace.go:171","msg":"trace[442951602] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1103; }","duration":"162.284562ms","start":"2026-07-23T23:56:59.47524Z","end":"2026-07-23T23:56:59.637524Z","steps":["trace[442951602] 'agreement among raft nodes before linearized reading' (duration: 162.080177ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:59.637548Z","caller":"traceutil/trace.go:171","msg":"trace[706956840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"369.48834ms","start":"2026-07-23T23:56:59.268047Z","end":"2026-07-23T23:56:59.637536Z","steps":["trace[706956840] 'agreement among raft nodes before linearized reading' (duration: 369.267044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.637547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.463892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T23:56:59.637581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.268016Z","time spent":"369.556562ms","remote":"127.0.0.1:46438","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-23T23:56:59.637618Z","caller":"traceutil/trace.go:171","msg":"trace[342736271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"368.542495ms","start":"2026-07-23T23:56:59.269058Z","end":"2026-07-23T23:56:59.6376Z","steps":["trace[342736271] 'agreement among raft nodes before linearized reading' (duration: 368.351239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.637662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.269036Z","time spent":"368.616236ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:56:59.893286Z","caller":"traceutil/trace.go:171","msg":"trace[2038100898] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"251.362872ms","start":"2026-07-23T23:56:59.641893Z","end":"2026-07-23T23:56:59.893256Z","steps":["trace[2038100898] 'process raft request' (duration: 215.862045ms)","trace[2038100898] 'compare' (duration: 35.345432ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:56:59.944497Z","caller":"traceutil/trace.go:171","msg":"trace[380847079] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1147; }","duration":"213.235703ms","start":"2026-07-23T23:56:59.731244Z","end":"2026-07-23T23:56:59.94448Z","steps":["trace[380847079] 'read index received' (duration: 126.56273ms)","trace[380847079] 'applied index is now lower than readState.Index' (duration: 86.672143ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:56:59.944532Z","caller":"traceutil/trace.go:171","msg":"trace[1700117636] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"302.236491ms","start":"2026-07-23T23:56:59.642274Z","end":"2026-07-23T23:56:59.944511Z","steps":["trace[1700117636] 'process raft request' (duration: 302.007065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.944593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.341636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:56:59.944617Z","caller":"traceutil/trace.go:171","msg":"trace[709582252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"213.405738ms","start":"2026-07-23T23:56:59.731203Z","end":"2026-07-23T23:56:59.944609Z","steps":["trace[709582252] 'agreement among raft nodes before linearized reading' (duration: 213.343756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.94465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.642263Z","time spent":"302.311113ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:56:59.944775Z","caller":"traceutil/trace.go:171","msg":"trace[1025198916] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"301.982554ms","start":"2026-07-23T23:56:59.642785Z","end":"2026-07-23T23:56:59.944768Z","steps":["trace[1025198916] 'process raft request' (duration: 301.600734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.944845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.642774Z","time spent":"302.018275ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:56:59.944928Z","caller":"traceutil/trace.go:171","msg":"trace[1422875838] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"301.673326ms","start":"2026-07-23T23:56:59.643222Z","end":"2026-07-23T23:56:59.944896Z","steps":["trace[1422875838] 'process raft request' (duration: 301.213773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:56:59.944961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:56:59.643202Z","time spent":"301.739417ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:01.46659Z","caller":"traceutil/trace.go:171","msg":"trace[1363132321] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"276.415351ms","start":"2026-07-23T23:57:01.190158Z","end":"2026-07-23T23:57:01.466573Z","steps":["trace[1363132321] 'process raft request' (duration: 276.305728ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:03.161819Z","caller":"traceutil/trace.go:171","msg":"trace[1586350323] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"131.226609ms","start":"2026-07-23T23:57:03.030563Z","end":"2026-07-23T23:57:03.16179Z","steps":["trace[1586350323] 'process raft request' (duration: 131.078925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:03.770924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.832277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:03.770996Z","caller":"traceutil/trace.go:171","msg":"trace[2041431] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1115; }","duration":"463.002122ms","start":"2026-07-23T23:57:03.307975Z","end":"2026-07-23T23:57:03.770977Z","steps":["trace[2041431] 'count revisions from in-memory index tree' (duration: 462.760295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:03.771007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.414103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:57:03.771022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.556365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-23T23:57:03.771071Z","caller":"traceutil/trace.go:171","msg":"trace[230651552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"511.528946ms","start":"2026-07-23T23:57:03.259525Z","end":"2026-07-23T23:57:03.771054Z","steps":["trace[230651552] 'range keys from in-memory index tree' (duration: 511.30965ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:03.771084Z","caller":"traceutil/trace.go:171","msg":"trace[1424406279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"300.671388ms","start":"2026-07-23T23:57:03.470392Z","end":"2026-07-23T23:57:03.771064Z","steps":["trace[1424406279] 'range keys from in-memory index tree' (duration: 300.421971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:03.771108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.470372Z","time spent":"300.73104ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:03.7711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.259477Z","time spent":"511.616788ms","remote":"127.0.0.1:39284","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-23T23:57:03.771429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.307951Z","time spent":"463.072424ms","remote":"127.0.0.1:46662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:57:04.186738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.546132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-23T23:57:04.18709Z","caller":"traceutil/trace.go:171","msg":"trace[1608486508] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"412.421801ms","start":"2026-07-23T23:57:03.774648Z","end":"2026-07-23T23:57:04.187069Z","steps":["trace[1608486508] 'process raft request' (duration: 412.202705ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:04.187109Z","caller":"traceutil/trace.go:171","msg":"trace[250594965] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"474.451296ms","start":"2026-07-23T23:57:03.712628Z","end":"2026-07-23T23:57:04.187079Z","steps":["trace[250594965] 'read index received' (duration: 52.583156ms)","trace[250594965] 'applied index is now lower than readState.Index' (duration: 421.86536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:04.187204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.774633Z","time spent":"412.503003ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:04.187373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.721473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:57:04.187429Z","caller":"traceutil/trace.go:171","msg":"trace[1589119009] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1116; }","duration":"474.836237ms","start":"2026-07-23T23:57:03.71258Z","end":"2026-07-23T23:57:04.187416Z","steps":["trace[1589119009] 'agreement among raft nodes before linearized reading' (duration: 474.645552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:04.18747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.712553Z","time spent":"474.907839ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:57:04.187509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.260389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:04.187602Z","caller":"traceutil/trace.go:171","msg":"trace[1574405138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"451.389853ms","start":"2026-07-23T23:57:03.736183Z","end":"2026-07-23T23:57:04.187573Z","steps":["trace[1574405138] 'agreement among raft nodes before linearized reading' (duration: 451.242049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:04.18766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:03.736172Z","time spent":"451.477245ms","remote":"127.0.0.1:39274","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-23T23:57:04.187722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.57466ms","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":"info","ts":"2026-07-23T23:57:04.187785Z","caller":"traceutil/trace.go:171","msg":"trace[765024085] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1116; }","duration":"130.647732ms","start":"2026-07-23T23:57:04.057119Z","end":"2026-07-23T23:57:04.187767Z","steps":["trace[765024085] 'agreement among raft nodes before linearized reading' (duration: 130.518048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:04.18779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.38209ms","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-23T23:57:04.187601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.001667ms","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-23T23:57:04.187841Z","caller":"traceutil/trace.go:171","msg":"trace[1758285907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1116; }","duration":"168.463693ms","start":"2026-07-23T23:57:04.019364Z","end":"2026-07-23T23:57:04.187828Z","steps":["trace[1758285907] 'agreement among raft nodes before linearized reading' (duration: 168.317119ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:04.187871Z","caller":"traceutil/trace.go:171","msg":"trace[1036113068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1116; }","duration":"169.304226ms","start":"2026-07-23T23:57:04.018555Z","end":"2026-07-23T23:57:04.18786Z","steps":["trace[1036113068] 'agreement among raft nodes before linearized reading' (duration: 168.867274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:04.187925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.350757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:57:04.187967Z","caller":"traceutil/trace.go:171","msg":"trace[23219916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1116; }","duration":"169.421678ms","start":"2026-07-23T23:57:04.018536Z","end":"2026-07-23T23:57:04.187957Z","steps":["trace[23219916] 'agreement among raft nodes before linearized reading' (duration: 169.280134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:04.188121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.500875ms","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-23T23:57:04.188259Z","caller":"traceutil/trace.go:171","msg":"trace[635734566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1116; }","duration":"211.694211ms","start":"2026-07-23T23:57:03.976542Z","end":"2026-07-23T23:57:04.188236Z","steps":["trace[635734566] 'agreement among raft nodes before linearized reading' (duration: 211.195157ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:04.371231Z","caller":"traceutil/trace.go:171","msg":"trace[223112621] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"129.662246ms","start":"2026-07-23T23:57:04.241546Z","end":"2026-07-23T23:57:04.371208Z","steps":["trace[223112621] 'process raft request' (duration: 70.600581ms)","trace[223112621] 'compare' (duration: 58.945391ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:20.407033Z","caller":"traceutil/trace.go:171","msg":"trace[362755253] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"142.513619ms","start":"2026-07-23T23:57:20.264494Z","end":"2026-07-23T23:57:20.407007Z","steps":["trace[362755253] 'read index received' (duration: 142.303344ms)","trace[362755253] 'applied index is now lower than readState.Index' (duration: 209.425µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:20.407358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.838998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-23T23:57:20.40742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.692269ms","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-23T23:57:20.407437Z","caller":"traceutil/trace.go:171","msg":"trace[1459923894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"142.967162ms","start":"2026-07-23T23:57:20.264453Z","end":"2026-07-23T23:57:20.40742Z","steps":["trace[1459923894] 'agreement among raft nodes before linearized reading' (duration: 142.732095ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:20.40746Z","caller":"traceutil/trace.go:171","msg":"trace[2145163818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"140.794961ms","start":"2026-07-23T23:57:20.266654Z","end":"2026-07-23T23:57:20.407449Z","steps":["trace[2145163818] 'agreement among raft nodes before linearized reading' (duration: 140.657728ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:20.407798Z","caller":"traceutil/trace.go:171","msg":"trace[301649257] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"202.987511ms","start":"2026-07-23T23:57:20.204777Z","end":"2026-07-23T23:57:20.407764Z","steps":["trace[301649257] 'process raft request' (duration: 202.089126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.893449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.432052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:57:34.893663Z","caller":"traceutil/trace.go:171","msg":"trace[999387692] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"386.058795ms","start":"2026-07-23T23:57:34.507585Z","end":"2026-07-23T23:57:34.893644Z","steps":["trace[999387692] 'read index received' (duration: 281.293694ms)","trace[999387692] 'applied index is now lower than readState.Index' (duration: 104.763911ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:34.893758Z","caller":"traceutil/trace.go:171","msg":"trace[1825802605] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"386.319012ms","start":"2026-07-23T23:57:34.507426Z","end":"2026-07-23T23:57:34.893745Z","steps":["trace[1825802605] 'process raft request' (duration: 281.463448ms)","trace[1825802605] 'compare' (duration: 104.285178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:34.893869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:34.507412Z","time spent":"386.416365ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:34.894316Z","caller":"traceutil/trace.go:171","msg":"trace[957203611] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"384.155673ms","start":"2026-07-23T23:57:34.510144Z","end":"2026-07-23T23:57:34.8943Z","steps":["trace[957203611] 'process raft request' (duration: 383.447603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.894357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.746244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:57:34.894377Z","caller":"traceutil/trace.go:171","msg":"trace[1226966804] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"382.868677ms","start":"2026-07-23T23:57:34.511478Z","end":"2026-07-23T23:57:34.894346Z","steps":["trace[1226966804] 'process raft request' (duration: 382.677752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.894454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:34.510133Z","time spent":"384.255325ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:34.894411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.362615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:34.894558Z","caller":"traceutil/trace.go:171","msg":"trace[1579524084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"162.54706ms","start":"2026-07-23T23:57:34.732003Z","end":"2026-07-23T23:57:34.894551Z","steps":["trace[1579524084] 'agreement among raft nodes before linearized reading' (duration: 162.360435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.894562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:34.511457Z","time spent":"383.022862ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:34.894418Z","caller":"traceutil/trace.go:171","msg":"trace[1596566666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"386.839597ms","start":"2026-07-23T23:57:34.507565Z","end":"2026-07-23T23:57:34.894405Z","steps":["trace[1596566666] 'agreement among raft nodes before linearized reading' (duration: 386.308212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.894642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:34.507556Z","time spent":"387.077113ms","remote":"127.0.0.1:46438","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-23T23:57:34.894886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.392662ms","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-23T23:57:34.894954Z","caller":"traceutil/trace.go:171","msg":"trace[1921741254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"383.487864ms","start":"2026-07-23T23:57:34.51145Z","end":"2026-07-23T23:57:34.894938Z","steps":["trace[1921741254] 'agreement among raft nodes before linearized reading' (duration: 382.843737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:34.894997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:34.511438Z","time spent":"383.548956ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-23T23:57:39.034427Z","caller":"traceutil/trace.go:171","msg":"trace[2040610397] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1366; }","duration":"301.608373ms","start":"2026-07-23T23:57:38.732793Z","end":"2026-07-23T23:57:39.034402Z","steps":["trace[2040610397] 'read index received' (duration: 225.031868ms)","trace[2040610397] 'applied index is now lower than readState.Index' (duration: 76.575415ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:39.034561Z","caller":"traceutil/trace.go:171","msg":"trace[361020529] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"572.634916ms","start":"2026-07-23T23:57:38.461913Z","end":"2026-07-23T23:57:39.034548Z","steps":["trace[361020529] 'process raft request' (duration: 495.901406ms)","trace[361020529] 'compare' (duration: 75.932608ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:39.034857Z","caller":"traceutil/trace.go:171","msg":"trace[599793858] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"537.786228ms","start":"2026-07-23T23:57:38.497048Z","end":"2026-07-23T23:57:39.034834Z","steps":["trace[599793858] 'process raft request' (duration: 537.256414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.034851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.037026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:39.034927Z","caller":"traceutil/trace.go:171","msg":"trace[2137656810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"302.151558ms","start":"2026-07-23T23:57:38.732758Z","end":"2026-07-23T23:57:39.03491Z","steps":["trace[2137656810] 'agreement among raft nodes before linearized reading' (duration: 301.8501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.034935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.726058ms","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-23T23:57:39.035074Z","caller":"traceutil/trace.go:171","msg":"trace[1437190015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"129.908683ms","start":"2026-07-23T23:57:38.905152Z","end":"2026-07-23T23:57:39.035061Z","steps":["trace[1437190015] 'agreement among raft nodes before linearized reading' (duration: 129.665366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.035085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.846641ms","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-23T23:57:39.035116Z","caller":"traceutil/trace.go:171","msg":"trace[1725314721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"129.893872ms","start":"2026-07-23T23:57:38.905216Z","end":"2026-07-23T23:57:39.03511Z","steps":["trace[1725314721] 'agreement among raft nodes before linearized reading' (duration: 129.785329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.034966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:38.732734Z","time spent":"302.223871ms","remote":"127.0.0.1:39284","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-23T23:57:39.035009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.770454ms","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-23T23:57:39.034974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:38.497028Z","time spent":"537.881741ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:39.035234Z","caller":"traceutil/trace.go:171","msg":"trace[48990596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"128.012351ms","start":"2026-07-23T23:57:38.907198Z","end":"2026-07-23T23:57:39.03521Z","steps":["trace[48990596] 'agreement among raft nodes before linearized reading' (duration: 127.699082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.035682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:38.461893Z","time spent":"572.701149ms","remote":"127.0.0.1:39332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:39.267218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.226683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:57:39.267411Z","caller":"traceutil/trace.go:171","msg":"trace[2004533950] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"227.949338ms","start":"2026-07-23T23:57:39.039446Z","end":"2026-07-23T23:57:39.267395Z","steps":["trace[2004533950] 'process raft request' (duration: 227.896327ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:39.26746Z","caller":"traceutil/trace.go:171","msg":"trace[339975706] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"228.00686ms","start":"2026-07-23T23:57:39.03943Z","end":"2026-07-23T23:57:39.267437Z","steps":["trace[339975706] 'process raft request' (duration: 126.493149ms)","trace[339975706] 'compare' (duration: 101.127281ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:39.267566Z","caller":"traceutil/trace.go:171","msg":"trace[1613227903] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"228.039911ms","start":"2026-07-23T23:57:39.039426Z","end":"2026-07-23T23:57:39.267466Z","steps":["trace[1613227903] 'process raft request' (duration: 227.869656ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:39.26764Z","caller":"traceutil/trace.go:171","msg":"trace[502864532] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1369; }","duration":"208.322299ms","start":"2026-07-23T23:57:39.05931Z","end":"2026-07-23T23:57:39.267632Z","steps":["trace[502864532] 'read index received' (duration: 106.637973ms)","trace[502864532] 'applied index is now lower than readState.Index' (duration: 101.683616ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:39.267711Z","caller":"traceutil/trace.go:171","msg":"trace[1260238768] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"227.561948ms","start":"2026-07-23T23:57:39.040137Z","end":"2026-07-23T23:57:39.267699Z","steps":["trace[1260238768] 'process raft request' (duration: 227.233959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.26779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.474833ms","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-23T23:57:39.267812Z","caller":"traceutil/trace.go:171","msg":"trace[1364876659] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1319; }","duration":"208.507433ms","start":"2026-07-23T23:57:39.059298Z","end":"2026-07-23T23:57:39.267805Z","steps":["trace[1364876659] 'agreement among raft nodes before linearized reading' (duration: 208.39469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:39.267936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.385173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:39.267968Z","caller":"traceutil/trace.go:171","msg":"trace[1918510688] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1319; }","duration":"175.447665ms","start":"2026-07-23T23:57:39.092512Z","end":"2026-07-23T23:57:39.26796Z","steps":["trace[1918510688] 'agreement among raft nodes before linearized reading' (duration: 175.396883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:40.347242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.080404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-23T23:57:40.347387Z","caller":"traceutil/trace.go:171","msg":"trace[1621723293] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1320; }","duration":"263.280949ms","start":"2026-07-23T23:57:40.08408Z","end":"2026-07-23T23:57:40.347361Z","steps":["trace[1621723293] 'range keys from in-memory index tree' (duration: 262.881538ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:40.81875Z","caller":"traceutil/trace.go:171","msg":"trace[918649948] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"466.94374ms","start":"2026-07-23T23:57:40.351777Z","end":"2026-07-23T23:57:40.818721Z","steps":["trace[918649948] 'process raft request' (duration: 466.666682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:40.81892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:40.351752Z","time spent":"467.075914ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:41.561751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115899525629481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:57:41.664896Z","caller":"traceutil/trace.go:171","msg":"trace[481733425] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"623.27862ms","start":"2026-07-23T23:57:41.041588Z","end":"2026-07-23T23:57:41.664866Z","steps":["trace[481733425] 'process raft request' (duration: 622.509689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.665044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.041563Z","time spent":"623.401782ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:41.688486Z","caller":"traceutil/trace.go:171","msg":"trace[1630444773] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1375; }","duration":"627.622169ms","start":"2026-07-23T23:57:41.060846Z","end":"2026-07-23T23:57:41.688468Z","steps":["trace[1630444773] 'read index received' (duration: 603.183217ms)","trace[1630444773] 'applied index is now lower than readState.Index' (duration: 24.438182ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:41.688556Z","caller":"traceutil/trace.go:171","msg":"trace[79549617] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"557.83971ms","start":"2026-07-23T23:57:41.130682Z","end":"2026-07-23T23:57:41.688522Z","steps":["trace[79549617] 'process raft request' (duration: 557.676946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.688665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.703986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:57:41.688686Z","caller":"traceutil/trace.go:171","msg":"trace[829540641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1323; }","duration":"417.785698ms","start":"2026-07-23T23:57:41.270893Z","end":"2026-07-23T23:57:41.688679Z","steps":["trace[829540641] 'agreement among raft nodes before linearized reading' (duration: 417.675675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.688707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.270876Z","time spent":"417.82641ms","remote":"127.0.0.1:46438","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-23T23:57:41.688735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.130659Z","time spent":"557.968323ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:41.688758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.886336ms","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-23T23:57:41.688781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.930215ms","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-23T23:57:41.688799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.158984ms","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-23T23:57:41.688842Z","caller":"traceutil/trace.go:171","msg":"trace[1425962270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1323; }","duration":"417.012708ms","start":"2026-07-23T23:57:41.271816Z","end":"2026-07-23T23:57:41.688829Z","steps":["trace[1425962270] 'agreement among raft nodes before linearized reading' (duration: 416.848783ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:41.688835Z","caller":"traceutil/trace.go:171","msg":"trace[992204111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1323; }","duration":"628.025739ms","start":"2026-07-23T23:57:41.06079Z","end":"2026-07-23T23:57:41.688816Z","steps":["trace[992204111] 'agreement among raft nodes before linearized reading' (duration: 627.778552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.688824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.623185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T23:57:41.688877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.271805Z","time spent":"417.063789ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:57:41.68888Z","caller":"traceutil/trace.go:171","msg":"trace[1802129003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1323; }","duration":"416.274886ms","start":"2026-07-23T23:57:41.272587Z","end":"2026-07-23T23:57:41.688862Z","steps":["trace[1802129003] 'agreement among raft nodes before linearized reading' (duration: 416.02102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.688895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.060768Z","time spent":"628.113082ms","remote":"127.0.0.1:46438","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-23T23:57:41.688912Z","caller":"traceutil/trace.go:171","msg":"trace[2123843660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"417.743597ms","start":"2026-07-23T23:57:41.271148Z","end":"2026-07-23T23:57:41.688892Z","steps":["trace[2123843660] 'agreement among raft nodes before linearized reading' (duration: 417.520971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:41.688934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.272578Z","time spent":"416.347389ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:41.688965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.271141Z","time spent":"417.813019ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:57:42.035976Z","caller":"traceutil/trace.go:171","msg":"trace[1397643586] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"343.468594ms","start":"2026-07-23T23:57:41.692487Z","end":"2026-07-23T23:57:42.035955Z","steps":["trace[1397643586] 'process raft request' (duration: 343.30964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:42.036113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.692481Z","time spent":"343.560986ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:42.036221Z","caller":"traceutil/trace.go:171","msg":"trace[686397748] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"343.67148ms","start":"2026-07-23T23:57:41.692527Z","end":"2026-07-23T23:57:42.036198Z","steps":["trace[686397748] 'process raft request' (duration: 343.396712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:42.036354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.692509Z","time spent":"343.776092ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:42.036438Z","caller":"traceutil/trace.go:171","msg":"trace[290265369] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"343.794213ms","start":"2026-07-23T23:57:41.692621Z","end":"2026-07-23T23:57:42.036415Z","steps":["trace[290265369] 'process raft request' (duration: 343.479034ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:42.036531Z","caller":"traceutil/trace.go:171","msg":"trace[168872668] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"343.34785ms","start":"2026-07-23T23:57:41.693168Z","end":"2026-07-23T23:57:42.036516Z","steps":["trace[168872668] 'process raft request' (duration: 343.192116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:42.036567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.692615Z","time spent":"343.881765ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:42.036593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:41.693165Z","time spent":"343.395132ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:42.339213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.93351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:57:42.339324Z","caller":"traceutil/trace.go:171","msg":"trace[383710290] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"298.682463ms","start":"2026-07-23T23:57:42.040621Z","end":"2026-07-23T23:57:42.339303Z","steps":["trace[383710290] 'process raft request' (duration: 31.384563ms)","trace[383710290] 'compare' (duration: 266.826677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:42.958541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.621034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:42.958624Z","caller":"traceutil/trace.go:171","msg":"trace[232125864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1329; }","duration":"225.762368ms","start":"2026-07-23T23:57:42.732842Z","end":"2026-07-23T23:57:42.958604Z","steps":["trace[232125864] 'range keys from in-memory index tree' (duration: 225.508401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:42.958764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.411728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:42.958823Z","caller":"traceutil/trace.go:171","msg":"trace[1709851166] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1329; }","duration":"189.496881ms","start":"2026-07-23T23:57:42.769311Z","end":"2026-07-23T23:57:42.958808Z","steps":["trace[1709851166] 'count revisions from in-memory index tree' (duration: 189.334796ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:43.479546Z","caller":"traceutil/trace.go:171","msg":"trace[2083823078] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"343.300031ms","start":"2026-07-23T23:57:43.136219Z","end":"2026-07-23T23:57:43.479519Z","steps":["trace[2083823078] 'read index received' (duration: 343.109715ms)","trace[2083823078] 'applied index is now lower than readState.Index' (duration: 189.226µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:43.479571Z","caller":"traceutil/trace.go:171","msg":"trace[370624890] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"433.166301ms","start":"2026-07-23T23:57:43.046375Z","end":"2026-07-23T23:57:43.479541Z","steps":["trace[370624890] 'process raft request' (duration: 433.001197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:43.479733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.046357Z","time spent":"433.298125ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:57:43.479736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.491856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:43.479942Z","caller":"traceutil/trace.go:171","msg":"trace[2074548186] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1330; }","duration":"343.749323ms","start":"2026-07-23T23:57:43.136177Z","end":"2026-07-23T23:57:43.479926Z","steps":["trace[2074548186] 'agreement among raft nodes before linearized reading' (duration: 343.434174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:43.480069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.136158Z","time spent":"343.873377ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-23T23:57:43.678877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.922406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:57:43.67897Z","caller":"traceutil/trace.go:171","msg":"trace[1081450993] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"193.777539ms","start":"2026-07-23T23:57:43.485175Z","end":"2026-07-23T23:57:43.678952Z","steps":["trace[1081450993] 'process raft request' (duration: 84.72289ms)","trace[1081450993] 'compare' (duration: 108.692189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:44.086515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.153259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:57:44.086636Z","caller":"traceutil/trace.go:171","msg":"trace[517672611] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"416.994307ms","start":"2026-07-23T23:57:43.66962Z","end":"2026-07-23T23:57:44.086614Z","steps":["trace[517672611] 'process raft request' (duration: 202.650792ms)","trace[517672611] 'compare' (duration: 213.980855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:44.086778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.669596Z","time spent":"417.1165ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:44.08715Z","caller":"traceutil/trace.go:171","msg":"trace[1877637985] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"395.240658ms","start":"2026-07-23T23:57:43.691899Z","end":"2026-07-23T23:57:44.08714Z","steps":["trace[1877637985] 'process raft request' (duration: 394.818256ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:44.087148Z","caller":"traceutil/trace.go:171","msg":"trace[388752901] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"403.744033ms","start":"2026-07-23T23:57:43.683384Z","end":"2026-07-23T23:57:44.087128Z","steps":["trace[388752901] 'process raft request' (duration: 403.243809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:44.087198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.691864Z","time spent":"395.31272ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:57:44.087229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.683363Z","time spent":"403.827094ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:57:44.087247Z","caller":"traceutil/trace.go:171","msg":"trace[1309198849] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"405.354386ms","start":"2026-07-23T23:57:43.681862Z","end":"2026-07-23T23:57:44.087216Z","steps":["trace[1309198849] 'read index received' (duration: 190.419476ms)","trace[1309198849] 'applied index is now lower than readState.Index' (duration: 214.93137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:44.108243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.354624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-23T23:57:44.108342Z","caller":"traceutil/trace.go:171","msg":"trace[1810484777] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"426.491188ms","start":"2026-07-23T23:57:43.68182Z","end":"2026-07-23T23:57:44.108311Z","steps":["trace[1810484777] 'agreement among raft nodes before linearized reading' (duration: 405.540552ms)","trace[1810484777] 'range keys from in-memory index tree' (duration: 20.72108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:44.108398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.681801Z","time spent":"426.58298ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-23T23:57:44.108433Z","caller":"traceutil/trace.go:171","msg":"trace[349505649] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"176.817592ms","start":"2026-07-23T23:57:43.931524Z","end":"2026-07-23T23:57:44.108341Z","steps":["trace[349505649] 'process raft request' (duration: 176.516374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:44.108731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.876106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:44.108762Z","caller":"traceutil/trace.go:171","msg":"trace[1901407712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"375.937088ms","start":"2026-07-23T23:57:43.732815Z","end":"2026-07-23T23:57:44.108752Z","steps":["trace[1901407712] 'agreement among raft nodes before linearized reading' (duration: 375.520527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:44.108799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.732802Z","time spent":"375.979899ms","remote":"127.0.0.1:39284","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-23T23:57:44.108934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.041198ms","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-23T23:57:44.108986Z","caller":"traceutil/trace.go:171","msg":"trace[1920361815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"349.13124ms","start":"2026-07-23T23:57:43.759842Z","end":"2026-07-23T23:57:44.108973Z","steps":["trace[1920361815] 'agreement among raft nodes before linearized reading' (duration: 348.534884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:44.109022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:43.75982Z","time spent":"349.191162ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:57:48.396598Z","caller":"traceutil/trace.go:171","msg":"trace[1856707073] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"263.358962ms","start":"2026-07-23T23:57:48.133221Z","end":"2026-07-23T23:57:48.39658Z","steps":["trace[1856707073] 'read index received' (duration: 263.178297ms)","trace[1856707073] 'applied index is now lower than readState.Index' (duration: 179.745µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:48.396722Z","caller":"traceutil/trace.go:171","msg":"trace[1782749692] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"298.101277ms","start":"2026-07-23T23:57:48.098593Z","end":"2026-07-23T23:57:48.396695Z","steps":["trace[1782749692] 'process raft request' (duration: 297.870911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:48.396788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.549527ms","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-23T23:57:48.39682Z","caller":"traceutil/trace.go:171","msg":"trace[929051533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1371; }","duration":"263.63091ms","start":"2026-07-23T23:57:48.13318Z","end":"2026-07-23T23:57:48.396811Z","steps":["trace[929051533] 'agreement among raft nodes before linearized reading' (duration: 263.509836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:48.396912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.58008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T23:57:48.396952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-23T23:57:48.396959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.530304ms","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-23T23:57:48.397004Z","caller":"traceutil/trace.go:171","msg":"trace[2129651724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"260.431881ms","start":"2026-07-23T23:57:48.136558Z","end":"2026-07-23T23:57:48.39699Z","steps":["trace[2129651724] 'agreement among raft nodes before linearized reading' (duration: 260.298748ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:48.397004Z","caller":"traceutil/trace.go:171","msg":"trace[409481597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"262.705214ms","start":"2026-07-23T23:57:48.134278Z","end":"2026-07-23T23:57:48.396983Z","steps":["trace[409481597] 'agreement among raft nodes before linearized reading' (duration: 262.472888ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:48.397022Z","caller":"traceutil/trace.go:171","msg":"trace[2068520558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"256.609557ms","start":"2026-07-23T23:57:48.140396Z","end":"2026-07-23T23:57:48.397005Z","steps":["trace[2068520558] 'agreement among raft nodes before linearized reading' (duration: 256.440382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:48.397025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.630415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T23:57:48.397076Z","caller":"traceutil/trace.go:171","msg":"trace[1816349261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"261.705456ms","start":"2026-07-23T23:57:48.135356Z","end":"2026-07-23T23:57:48.397061Z","steps":["trace[1816349261] 'agreement among raft nodes before linearized reading' (duration: 261.556562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:48.892354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.682579ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-23T23:57:48.892467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.397821Z","time spent":"494.641212ms","remote":"127.0.0.1:39332","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-23T23:57:50.135454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.339573335s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T23:57:50.177108Z","caller":"traceutil/trace.go:171","msg":"trace[1621026895] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.776034787s","start":"2026-07-23T23:57:48.401047Z","end":"2026-07-23T23:57:50.177082Z","steps":["trace[1621026895] 'process raft request' (duration: 1.734620638s)","trace[1621026895] 'compare' (duration: 40.788711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:50.177244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.40103Z","time spent":"1.7761456s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:50.177344Z","caller":"traceutil/trace.go:171","msg":"trace[444834728] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.775896693s","start":"2026-07-23T23:57:48.401423Z","end":"2026-07-23T23:57:50.177319Z","steps":["trace[444834728] 'process raft request' (duration: 1.775571134s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.177464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.401402Z","time spent":"1.775991275s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:50.177676Z","caller":"traceutil/trace.go:171","msg":"trace[2103341730] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.77615091s","start":"2026-07-23T23:57:48.401512Z","end":"2026-07-23T23:57:50.177663Z","steps":["trace[2103341730] 'process raft request' (duration: 1.775747078s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.177741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.4015Z","time spent":"1.776209572s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:50.177965Z","caller":"traceutil/trace.go:171","msg":"trace[1692390410] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.776346715s","start":"2026-07-23T23:57:48.401588Z","end":"2026-07-23T23:57:50.177935Z","steps":["trace[1692390410] 'process raft request' (duration: 1.776031236s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.178064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.401567Z","time spent":"1.776451038s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:50.178241Z","caller":"traceutil/trace.go:171","msg":"trace[2068493156] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"1.284768069s","start":"2026-07-23T23:57:48.893456Z","end":"2026-07-23T23:57:50.178224Z","steps":["trace[2068493156] 'process raft request' (duration: 1.284685407s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:50.178248Z","caller":"traceutil/trace.go:171","msg":"trace[802131960] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"1.776014526s","start":"2026-07-23T23:57:48.402221Z","end":"2026-07-23T23:57:50.178235Z","steps":["trace[802131960] 'process raft request' (duration: 1.775625326s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.178329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.402195Z","time spent":"1.776101909s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:50.178495Z","caller":"traceutil/trace.go:171","msg":"trace[884151407] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1426; }","duration":"1.718623858s","start":"2026-07-23T23:57:48.459861Z","end":"2026-07-23T23:57:50.178485Z","steps":["trace[884151407] 'read index received' (duration: 291.884147ms)","trace[884151407] 'applied index is now lower than readState.Index' (duration: 1.426738561s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:50.178696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052292326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-23T23:57:50.178726Z","caller":"traceutil/trace.go:171","msg":"trace[1801245322] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"1.052324077s","start":"2026-07-23T23:57:49.126393Z","end":"2026-07-23T23:57:50.178717Z","steps":["trace[1801245322] 'agreement among raft nodes before linearized reading' (duration: 1.052172012s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.178714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718838324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:57:50.178762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:49.12634Z","time spent":"1.05241487s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-23T23:57:50.178782Z","caller":"traceutil/trace.go:171","msg":"trace[1115432539] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1377; }","duration":"1.718945607s","start":"2026-07-23T23:57:48.459819Z","end":"2026-07-23T23:57:50.178765Z","steps":["trace[1115432539] 'agreement among raft nodes before linearized reading' (duration: 1.718728991s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.178789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.893427Z","time spent":"1.284852642s","remote":"127.0.0.1:39332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:50.178825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:48.459807Z","time spent":"1.719009039s","remote":"127.0.0.1:39410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:57:50.178836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.778858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:57:50.178885Z","caller":"traceutil/trace.go:171","msg":"trace[1692499110] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1377; }","duration":"125.83472ms","start":"2026-07-23T23:57:50.053038Z","end":"2026-07-23T23:57:50.178872Z","steps":["trace[1692499110] 'agreement among raft nodes before linearized reading' (duration: 125.739697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.178885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.231851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:57:50.17904Z","caller":"traceutil/trace.go:171","msg":"trace[28827911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"446.417456ms","start":"2026-07-23T23:57:49.732608Z","end":"2026-07-23T23:57:50.179026Z","steps":["trace[28827911] 'agreement among raft nodes before linearized reading' (duration: 446.235531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.179089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:49.732592Z","time spent":"446.482357ms","remote":"127.0.0.1:39284","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-23T23:57:50.179163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.673173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-23T23:57:50.179204Z","caller":"traceutil/trace.go:171","msg":"trace[344026085] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1377; }","duration":"710.744325ms","start":"2026-07-23T23:57:49.468448Z","end":"2026-07-23T23:57:50.179192Z","steps":["trace[344026085] 'agreement among raft nodes before linearized reading' (duration: 710.617421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:50.179379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:49.468433Z","time spent":"710.910779ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}