{"level":"warn","ts":"2026-05-29T05:07:40.694462Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:07:40.694579Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-05-29T05:07:40.69467Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:07:40.694688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-29T05:07:40.694716Z","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-05-29T05:07:40.695635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-29T05:07:40.695742Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-05-29T05:07:40.70251Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.500062ms"} {"level":"info","ts":"2026-05-29T05:07:40.714464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-29T05:07:40.714587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:07:40.714627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:07:40.714669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:07:40.71469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:07:40.714762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-29T05:07:40.721868Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:07:40.723026Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:07:40.724066Z","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-05-29T05:07:40.726968Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:07:40.727481Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:07:40.727513Z","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-05-29T05:07:40.732401Z","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-05-29T05:07:40.732427Z","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-05-29T05:07:40.733066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-29T05:07:40.737659Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-29T05:07:40.739081Z","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-05-29T05:07:40.739277Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:07:40.739303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:07:40.739403Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-29T05:07:40.73943Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-29T05:07:40.815803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:07:40.815857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:07:40.815968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-29T05:07:40.816068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:07:40.816154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-29T05:07:40.816226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:07:40.816313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-29T05:07:40.817191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:07:40.817287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:07:40.817259Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:07:40.817374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:07:40.817734Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:07:40.817814Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:07:40.819144Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:07:40.819266Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:07:40.819307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:07:40.820571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-29T05:07:40.82376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T05:08:16.436987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764671029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:08:16.53066Z","caller":"traceutil/trace.go:171","msg":"trace[56019851] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"594.208485ms","start":"2026-05-29T05:08:15.936426Z","end":"2026-05-29T05:08:16.530635Z","steps":["trace[56019851] 'read index received' (duration: 594.007751ms)","trace[56019851] 'applied index is now lower than readState.Index' (duration: 200.014µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:16.530781Z","caller":"traceutil/trace.go:171","msg":"trace[341686922] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"602.160945ms","start":"2026-05-29T05:08:15.928597Z","end":"2026-05-29T05:08:16.530758Z","steps":["trace[341686922] 'process raft request' (duration: 601.893038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:16.531033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.253051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-29T05:08:16.531013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.349829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:16.531174Z","caller":"traceutil/trace.go:171","msg":"trace[1067683043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"270.439497ms","start":"2026-05-29T05:08:16.260711Z","end":"2026-05-29T05:08:16.53115Z","steps":["trace[1067683043] 'agreement among raft nodes before linearized reading' (duration: 270.158829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:16.531034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.473619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:08:16.531186Z","caller":"traceutil/trace.go:171","msg":"trace[786829778] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:479; }","duration":"594.783091ms","start":"2026-05-29T05:08:15.936385Z","end":"2026-05-29T05:08:16.531168Z","steps":["trace[786829778] 'agreement among raft nodes before linearized reading' (duration: 594.363799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:16.531331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:15.928578Z","time spent":"602.249966ms","remote":"127.0.0.1:42018","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-05-29T05:08:16.531392Z","caller":"traceutil/trace.go:171","msg":"trace[706941430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"110.881381ms","start":"2026-05-29T05:08:16.420489Z","end":"2026-05-29T05:08:16.53137Z","steps":["trace[706941430] 'agreement among raft nodes before linearized reading' (duration: 110.414178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:16.531412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:15.936356Z","time spent":"595.030987ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-29T05:08:22.822001Z","caller":"traceutil/trace.go:171","msg":"trace[222938518] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"264.663107ms","start":"2026-05-29T05:08:22.557273Z","end":"2026-05-29T05:08:22.821936Z","steps":["trace[222938518] 'read index received' (duration: 263.402756ms)","trace[222938518] 'applied index is now lower than readState.Index' (duration: 1.259341ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:22.822048Z","caller":"traceutil/trace.go:171","msg":"trace[1297002155] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"293.920346ms","start":"2026-05-29T05:08:22.528096Z","end":"2026-05-29T05:08:22.822017Z","steps":["trace[1297002155] 'process raft request' (duration: 292.966862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:22.822416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.066346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:08:22.822514Z","caller":"traceutil/trace.go:171","msg":"trace[1055099560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"265.21773ms","start":"2026-05-29T05:08:22.557235Z","end":"2026-05-29T05:08:22.822453Z","steps":["trace[1055099560] 'agreement among raft nodes before linearized reading' (duration: 264.935573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:22.822578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.914412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:08:22.822574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.927859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:08:22.822631Z","caller":"traceutil/trace.go:171","msg":"trace[925526728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"146.011344ms","start":"2026-05-29T05:08:22.676606Z","end":"2026-05-29T05:08:22.822617Z","steps":["trace[925526728] 'agreement among raft nodes before linearized reading' (duration: 145.869501ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:22.822675Z","caller":"traceutil/trace.go:171","msg":"trace[661099678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"264.045252ms","start":"2026-05-29T05:08:22.558608Z","end":"2026-05-29T05:08:22.822653Z","steps":["trace[661099678] 'agreement among raft nodes before linearized reading' (duration: 263.786116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:23.363476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.476252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:08:23.363938Z","caller":"traceutil/trace.go:171","msg":"trace[2121011856] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:608; }","duration":"243.695463ms","start":"2026-05-29T05:08:23.120225Z","end":"2026-05-29T05:08:23.363921Z","steps":["trace[2121011856] 'read index received' (duration: 82.332µs)","trace[2121011856] 'applied index is now lower than readState.Index' (duration: 243.611991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:23.364102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.882897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:23.364167Z","caller":"traceutil/trace.go:171","msg":"trace[1268899195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"244.010811ms","start":"2026-05-29T05:08:23.120146Z","end":"2026-05-29T05:08:23.364157Z","steps":["trace[1268899195] 'agreement among raft nodes before linearized reading' (duration: 243.863667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:23.364146Z","caller":"traceutil/trace.go:171","msg":"trace[706330271] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"521.981874ms","start":"2026-05-29T05:08:22.842115Z","end":"2026-05-29T05:08:23.364097Z","steps":["trace[706330271] 'process raft request' (duration: 521.612085ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:23.364199Z","caller":"traceutil/trace.go:171","msg":"trace[572007395] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"510.319398ms","start":"2026-05-29T05:08:22.853832Z","end":"2026-05-29T05:08:23.364151Z","steps":["trace[572007395] 'process raft request' (duration: 509.975959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:23.364351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:22.8421Z","time spent":"522.143829ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:08:23.36437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:22.853825Z","time spent":"510.459001ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:08:23.364546Z","caller":"traceutil/trace.go:171","msg":"trace[1044997307] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"463.229172ms","start":"2026-05-29T05:08:22.901305Z","end":"2026-05-29T05:08:23.364534Z","steps":["trace[1044997307] 'process raft request' (duration: 462.552206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:23.364637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:22.901286Z","time spent":"463.320285ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:08:23.364652Z","caller":"traceutil/trace.go:171","msg":"trace[124415285] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"528.904154ms","start":"2026-05-29T05:08:22.835727Z","end":"2026-05-29T05:08:23.364631Z","steps":["trace[124415285] 'process raft request' (duration: 172.71985ms)","trace[124415285] 'compare' (duration: 354.248926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:23.364795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:22.835705Z","time spent":"529.052137ms","remote":"127.0.0.1:42018","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-05-29T05:08:23.366634Z","caller":"traceutil/trace.go:171","msg":"trace[1867480174] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"523.84521ms","start":"2026-05-29T05:08:22.842762Z","end":"2026-05-29T05:08:23.366608Z","steps":["trace[1867480174] 'process raft request' (duration: 520.870717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:23.366817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:22.842753Z","time spent":"523.971083ms","remote":"127.0.0.1:42018","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-05-29T05:08:24.305221Z","caller":"traceutil/trace.go:171","msg":"trace[801065134] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"366.60711ms","start":"2026-05-29T05:08:23.938589Z","end":"2026-05-29T05:08:24.305196Z","steps":["trace[801065134] 'read index received' (duration: 365.756418ms)","trace[801065134] 'applied index is now lower than readState.Index' (duration: 802.511µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:24.305534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.929457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T05:08:24.305542Z","caller":"traceutil/trace.go:171","msg":"trace[1626192872] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"513.075855ms","start":"2026-05-29T05:08:23.792402Z","end":"2026-05-29T05:08:24.305478Z","steps":["trace[1626192872] 'process raft request' (duration: 512.234403ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.305656Z","caller":"traceutil/trace.go:171","msg":"trace[1476717275] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:592; }","duration":"367.069351ms","start":"2026-05-29T05:08:23.938573Z","end":"2026-05-29T05:08:24.305643Z","steps":["trace[1476717275] 'agreement among raft nodes before linearized reading' (duration: 366.885436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:24.305939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:23.938512Z","time spent":"367.379909ms","remote":"127.0.0.1:41784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-05-29T05:08:24.305973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:23.792383Z","time spent":"513.364462ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:08:25.362616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764671287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:25.86345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764671287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:26.364162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764671287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:26.582798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.218225425s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:08:26.583642Z","caller":"traceutil/trace.go:171","msg":"trace[2128639645] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"1.720847934s","start":"2026-05-29T05:08:24.862525Z","end":"2026-05-29T05:08:26.583373Z","steps":["trace[2128639645] 'read index received' (duration: 1.720560887s)","trace[2128639645] 'applied index is now lower than readState.Index' (duration: 285.797µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:26.58397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721410308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T05:08:26.584027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212219979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:08:26.584064Z","caller":"traceutil/trace.go:171","msg":"trace[735710877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"1.721535831s","start":"2026-05-29T05:08:24.862505Z","end":"2026-05-29T05:08:26.584041Z","steps":["trace[735710877] 'agreement among raft nodes before linearized reading' (duration: 1.721097551s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:26.584109Z","caller":"traceutil/trace.go:171","msg":"trace[855462439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"1.212333031s","start":"2026-05-29T05:08:25.371752Z","end":"2026-05-29T05:08:26.584085Z","steps":["trace[855462439] 'agreement among raft nodes before linearized reading' (duration: 1.212068435s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:26.584098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212335882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:08:26.584136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:24.862412Z","time spent":"1.721715075s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:08:26.584166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:25.371747Z","time spent":"1.212405143s","remote":"127.0.0.1:42018","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-05-29T05:08:26.584192Z","caller":"traceutil/trace.go:171","msg":"trace[720914126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"1.212439694s","start":"2026-05-29T05:08:25.371729Z","end":"2026-05-29T05:08:26.584169Z","steps":["trace[720914126] 'agreement among raft nodes before linearized reading' (duration: 1.212145227s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:26.584239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:25.371667Z","time spent":"1.212563337s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:26.584291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211996062s","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-05-29T05:08:26.584375Z","caller":"traceutil/trace.go:171","msg":"trace[1359072130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"1.212126176s","start":"2026-05-29T05:08:25.37223Z","end":"2026-05-29T05:08:26.584357Z","steps":["trace[1359072130] 'agreement among raft nodes before linearized reading' (duration: 1.211552282s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:26.584363Z","caller":"traceutil/trace.go:171","msg":"trace[1726715890] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.219050264s","start":"2026-05-29T05:08:24.364408Z","end":"2026-05-29T05:08:26.583458Z","steps":["trace[1726715890] 'process raft request' (duration: 2.218764527s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:26.584421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:25.372197Z","time spent":"1.212212477s","remote":"127.0.0.1:42018","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-05-29T05:08:26.584505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:24.364375Z","time spent":"2.220057699s","remote":"127.0.0.1:41992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:08:26.58458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46810625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:26.584744Z","caller":"traceutil/trace.go:171","msg":"trace[717565585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.468225503s","start":"2026-05-29T05:08:25.11643Z","end":"2026-05-29T05:08:26.584656Z","steps":["trace[717565585] 'agreement among raft nodes before linearized reading' (duration: 1.467334432s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:26.58479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:25.116404Z","time spent":"1.468372206s","remote":"127.0.0.1:41758","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-05-29T05:08:27.065716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.08954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:08:27.065829Z","caller":"traceutil/trace.go:171","msg":"trace[29127292] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"236.965178ms","start":"2026-05-29T05:08:26.828841Z","end":"2026-05-29T05:08:27.065806Z","steps":["trace[29127292] 'process raft request' (duration: 98.705244ms)","trace[29127292] 'compare' (duration: 137.899275ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:27.066174Z","caller":"traceutil/trace.go:171","msg":"trace[533367434] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"228.637272ms","start":"2026-05-29T05:08:26.837512Z","end":"2026-05-29T05:08:27.066149Z","steps":["trace[533367434] 'process raft request' (duration: 228.52326ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:28.400321Z","caller":"traceutil/trace.go:171","msg":"trace[1216179479] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"422.936053ms","start":"2026-05-29T05:08:27.977361Z","end":"2026-05-29T05:08:28.400297Z","steps":["trace[1216179479] 'process raft request' (duration: 422.587034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:28.400444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.752021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:28.400495Z","caller":"traceutil/trace.go:171","msg":"trace[455347793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"282.812903ms","start":"2026-05-29T05:08:28.117669Z","end":"2026-05-29T05:08:28.400482Z","steps":["trace[455347793] 'agreement among raft nodes before linearized reading' (duration: 282.70381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:28.400814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:27.977345Z","time spent":"423.053795ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:08:28.400325Z","caller":"traceutil/trace.go:171","msg":"trace[1817852707] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"282.579016ms","start":"2026-05-29T05:08:28.117695Z","end":"2026-05-29T05:08:28.400274Z","steps":["trace[1817852707] 'read index received' (duration: 282.272449ms)","trace[1817852707] 'applied index is now lower than readState.Index' (duration: 305.457µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:38.857119Z","caller":"traceutil/trace.go:171","msg":"trace[2124344808] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"202.515481ms","start":"2026-05-29T05:08:38.654576Z","end":"2026-05-29T05:08:38.857091Z","steps":["trace[2124344808] 'read index received' (duration: 202.215024ms)","trace[2124344808] 'applied index is now lower than readState.Index' (duration: 298.947µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:38.857206Z","caller":"traceutil/trace.go:171","msg":"trace[1895857381] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"205.054054ms","start":"2026-05-29T05:08:38.652134Z","end":"2026-05-29T05:08:38.857188Z","steps":["trace[1895857381] 'process raft request' (duration: 204.735496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:38.857363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.775307ms","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-05-29T05:08:38.857427Z","caller":"traceutil/trace.go:171","msg":"trace[1307148643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"202.8971ms","start":"2026-05-29T05:08:38.654514Z","end":"2026-05-29T05:08:38.857411Z","steps":["trace[1307148643] 'agreement among raft nodes before linearized reading' (duration: 202.722116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:38.857519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.186953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:08:38.85756Z","caller":"traceutil/trace.go:171","msg":"trace[1141728860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"202.250294ms","start":"2026-05-29T05:08:38.6553Z","end":"2026-05-29T05:08:38.85755Z","steps":["trace[1141728860] 'agreement among raft nodes before linearized reading' (duration: 202.122781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:38.85886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.281001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:08:38.858986Z","caller":"traceutil/trace.go:171","msg":"trace[342237351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"203.945387ms","start":"2026-05-29T05:08:38.655022Z","end":"2026-05-29T05:08:38.858967Z","steps":["trace[342237351] 'agreement among raft nodes before linearized reading' (duration: 202.349947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.03542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.095513ms","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-05-29T05:08:41.03547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.260777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:08:41.035506Z","caller":"traceutil/trace.go:171","msg":"trace[1846656335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"165.239056ms","start":"2026-05-29T05:08:40.870247Z","end":"2026-05-29T05:08:41.035486Z","steps":["trace[1846656335] 'range keys from in-memory index tree' (duration: 164.910518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.03547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.716536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kr8qz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-29T05:08:41.035538Z","caller":"traceutil/trace.go:171","msg":"trace[2061661706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"165.358599ms","start":"2026-05-29T05:08:40.870162Z","end":"2026-05-29T05:08:41.03552Z","steps":["trace[2061661706] 'range keys from in-memory index tree' (duration: 165.056572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.035515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.017486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T05:08:41.035559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.304911ms","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-05-29T05:08:41.035592Z","caller":"traceutil/trace.go:171","msg":"trace[1993899401] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kr8qz; range_end:; response_count:1; response_revision:751; }","duration":"114.868069ms","start":"2026-05-29T05:08:40.920705Z","end":"2026-05-29T05:08:41.035573Z","steps":["trace[1993899401] 'range keys from in-memory index tree' (duration: 114.566253ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:41.035632Z","caller":"traceutil/trace.go:171","msg":"trace[1165038187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"175.123829ms","start":"2026-05-29T05:08:40.860474Z","end":"2026-05-29T05:08:41.035598Z","steps":["trace[1165038187] 'range keys from in-memory index tree' (duration: 174.862503ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:41.035636Z","caller":"traceutil/trace.go:171","msg":"trace[1559713151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"168.441994ms","start":"2026-05-29T05:08:40.867174Z","end":"2026-05-29T05:08:41.035616Z","steps":["trace[1559713151] 'range keys from in-memory index tree' (duration: 168.111527ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:41.517799Z","caller":"traceutil/trace.go:171","msg":"trace[820964665] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"478.321163ms","start":"2026-05-29T05:08:41.039453Z","end":"2026-05-29T05:08:41.517774Z","steps":["trace[820964665] 'process raft request' (duration: 478.164009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.518587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.039424Z","time spent":"478.595399ms","remote":"127.0.0.1:42018","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-05-29T05:08:41.528355Z","caller":"traceutil/trace.go:171","msg":"trace[1696381895] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"487.671691ms","start":"2026-05-29T05:08:41.040664Z","end":"2026-05-29T05:08:41.528336Z","steps":["trace[1696381895] 'process raft request' (duration: 487.585629ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:41.528346Z","caller":"traceutil/trace.go:171","msg":"trace[1283529628] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"487.720413ms","start":"2026-05-29T05:08:41.040599Z","end":"2026-05-29T05:08:41.52832Z","steps":["trace[1283529628] 'process raft request' (duration: 487.560369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.528484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.040638Z","time spent":"487.774514ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:08:41.52853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.040577Z","time spent":"487.847707ms","remote":"127.0.0.1:42018","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-05-29T05:08:41.528644Z","caller":"traceutil/trace.go:171","msg":"trace[778973178] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"487.296142ms","start":"2026-05-29T05:08:41.041327Z","end":"2026-05-29T05:08:41.528623Z","steps":["trace[778973178] 'process raft request' (duration: 486.956454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.528741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.041313Z","time spent":"487.384305ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:08:41.528939Z","caller":"traceutil/trace.go:171","msg":"trace[1283940015] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"487.102498ms","start":"2026-05-29T05:08:41.04182Z","end":"2026-05-29T05:08:41.528923Z","steps":["trace[1283940015] 'process raft request' (duration: 486.718038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.529013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.041805Z","time spent":"487.17484ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:08:41.529104Z","caller":"traceutil/trace.go:171","msg":"trace[418680300] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:779; }","duration":"409.715217ms","start":"2026-05-29T05:08:41.119369Z","end":"2026-05-29T05:08:41.529085Z","steps":["trace[418680300] 'read index received' (duration: 398.199755ms)","trace[418680300] 'applied index is now lower than readState.Index' (duration: 11.513632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:41.529184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.816851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:41.529221Z","caller":"traceutil/trace.go:171","msg":"trace[780139039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"409.899603ms","start":"2026-05-29T05:08:41.119312Z","end":"2026-05-29T05:08:41.529211Z","steps":["trace[780139039] 'agreement among raft nodes before linearized reading' (duration: 409.835991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:41.529256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:41.11929Z","time spent":"409.959333ms","remote":"127.0.0.1:41758","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-05-29T05:09:15.867645Z","caller":"traceutil/trace.go:171","msg":"trace[340552198] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"192.910426ms","start":"2026-05-29T05:09:15.674711Z","end":"2026-05-29T05:09:15.867621Z","steps":["trace[340552198] 'process raft request' (duration: 192.766152ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:16.687044Z","caller":"traceutil/trace.go:171","msg":"trace[1098409225] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.012178277s","start":"2026-05-29T05:09:15.674835Z","end":"2026-05-29T05:09:16.687013Z","steps":["trace[1098409225] 'process raft request' (duration: 1.011014669s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:16.687117Z","caller":"traceutil/trace.go:171","msg":"trace[939690056] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.010831204s","start":"2026-05-29T05:09:15.676276Z","end":"2026-05-29T05:09:16.687108Z","steps":["trace[939690056] 'process raft request' (duration: 1.010559708s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:16.68719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:15.674815Z","time spent":"1.01229661s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:09:16.687221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:15.676264Z","time spent":"1.010878736s","remote":"127.0.0.1:42018","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-05-29T05:09:16.687055Z","caller":"traceutil/trace.go:171","msg":"trace[1782261041] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.010574569s","start":"2026-05-29T05:09:15.676458Z","end":"2026-05-29T05:09:16.687033Z","steps":["trace[1782261041] 'process raft request' (duration: 1.010459946s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:16.687392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:15.676449Z","time spent":"1.010917067s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:09:16.687622Z","caller":"traceutil/trace.go:171","msg":"trace[962394083] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"572.031713ms","start":"2026-05-29T05:09:16.115207Z","end":"2026-05-29T05:09:16.687238Z","steps":["trace[962394083] 'process raft request' (duration: 571.770866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:16.687835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:16.11518Z","time spent":"572.558175ms","remote":"127.0.0.1:42018","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-05-29T05:09:38.41634Z","caller":"traceutil/trace.go:171","msg":"trace[1373575315] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"346.932283ms","start":"2026-05-29T05:09:38.069375Z","end":"2026-05-29T05:09:38.416308Z","steps":["trace[1373575315] 'read index received' (duration: 346.698077ms)","trace[1373575315] 'applied index is now lower than readState.Index' (duration: 232.696µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:38.416513Z","caller":"traceutil/trace.go:171","msg":"trace[1541220983] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"415.120824ms","start":"2026-05-29T05:09:38.001375Z","end":"2026-05-29T05:09:38.416495Z","steps":["trace[1541220983] 'process raft request' (duration: 414.696744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.416661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.001357Z","time spent":"415.207026ms","remote":"127.0.0.1:42018","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-05-29T05:09:38.416895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.503276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:09:38.41694Z","caller":"traceutil/trace.go:171","msg":"trace[1996663116] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1037; }","duration":"347.61446ms","start":"2026-05-29T05:09:38.069313Z","end":"2026-05-29T05:09:38.416927Z","steps":["trace[1996663116] 'agreement among raft nodes before linearized reading' (duration: 347.520947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.416973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.069284Z","time spent":"347.67986ms","remote":"127.0.0.1:42136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:09:38.417059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.419436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T05:09:38.417101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.187018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:38.417153Z","caller":"traceutil/trace.go:171","msg":"trace[1586603745] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1037; }","duration":"345.59496ms","start":"2026-05-29T05:09:38.071538Z","end":"2026-05-29T05:09:38.417133Z","steps":["trace[1586603745] 'agreement among raft nodes before linearized reading' (duration: 345.403276ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:38.41721Z","caller":"traceutil/trace.go:171","msg":"trace[896276045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"297.361872ms","start":"2026-05-29T05:09:38.119828Z","end":"2026-05-29T05:09:38.41719Z","steps":["trace[896276045] 'agreement among raft nodes before linearized reading' (duration: 297.133516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:38.417217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:38.071507Z","time spent":"345.699482ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:09:38.923464Z","caller":"traceutil/trace.go:171","msg":"trace[1307453634] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"121.659757ms","start":"2026-05-29T05:09:38.801782Z","end":"2026-05-29T05:09:38.923441Z","steps":["trace[1307453634] 'process raft request' (duration: 121.546874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:40.2083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.05522ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:09:40.208508Z","caller":"traceutil/trace.go:171","msg":"trace[1086462914] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"563.163669ms","start":"2026-05-29T05:09:39.645324Z","end":"2026-05-29T05:09:40.208488Z","steps":["trace[1086462914] 'read index received' (duration: 57.621µs)","trace[1086462914] 'applied index is now lower than readState.Index' (duration: 563.095118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:40.208653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.301163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:40.208726Z","caller":"traceutil/trace.go:171","msg":"trace[853923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1043; }","duration":"563.426065ms","start":"2026-05-29T05:09:39.645284Z","end":"2026-05-29T05:09:40.20871Z","steps":["trace[853923] 'agreement among raft nodes before linearized reading' (duration: 563.299272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:40.208759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:39.645263Z","time spent":"563.486147ms","remote":"127.0.0.1:44062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:09:40.208665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.521072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:09:40.20916Z","caller":"traceutil/trace.go:171","msg":"trace[1801408614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"328.056005ms","start":"2026-05-29T05:09:39.881089Z","end":"2026-05-29T05:09:40.209145Z","steps":["trace[1801408614] 'agreement among raft nodes before linearized reading' (duration: 327.531382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:40.209237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:39.881069Z","time spent":"328.150587ms","remote":"127.0.0.1:41758","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-05-29T05:09:54.803386Z","caller":"traceutil/trace.go:171","msg":"trace[1418027069] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"186.987408ms","start":"2026-05-29T05:09:54.616369Z","end":"2026-05-29T05:09:54.803356Z","steps":["trace[1418027069] 'process raft request' (duration: 138.866603ms)","trace[1418027069] 'compare' (duration: 47.89638ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:54.969926Z","caller":"traceutil/trace.go:171","msg":"trace[1273493532] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"140.009711ms","start":"2026-05-29T05:09:54.829883Z","end":"2026-05-29T05:09:54.969893Z","steps":["trace[1273493532] 'process raft request' (duration: 102.919733ms)","trace[1273493532] 'compare' (duration: 36.841282ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:55.526531Z","caller":"traceutil/trace.go:171","msg":"trace[1674851500] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"150.588707ms","start":"2026-05-29T05:09:55.375898Z","end":"2026-05-29T05:09:55.526487Z","steps":["trace[1674851500] 'process raft request' (duration: 116.125462ms)","trace[1674851500] 'compare' (duration: 34.327142ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:55.593244Z","caller":"traceutil/trace.go:171","msg":"trace[1768063367] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"211.948133ms","start":"2026-05-29T05:09:55.381267Z","end":"2026-05-29T05:09:55.593215Z","steps":["trace[1768063367] 'process raft request' (duration: 211.873991ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:55.593247Z","caller":"traceutil/trace.go:171","msg":"trace[437912063] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"214.528085ms","start":"2026-05-29T05:09:55.378687Z","end":"2026-05-29T05:09:55.593216Z","steps":["trace[437912063] 'process raft request' (duration: 214.297079ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:55.662829Z","caller":"traceutil/trace.go:171","msg":"trace[943126854] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"130.733146ms","start":"2026-05-29T05:09:55.532051Z","end":"2026-05-29T05:09:55.662784Z","steps":["trace[943126854] 'process raft request' (duration: 111.676044ms)","trace[943126854] 'compare' (duration: 18.773715ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:55.662991Z","caller":"traceutil/trace.go:171","msg":"trace[593389540] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"128.450161ms","start":"2026-05-29T05:09:55.534513Z","end":"2026-05-29T05:09:55.662964Z","steps":["trace[593389540] 'process raft request' (duration: 128.297797ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:55.89475Z","caller":"traceutil/trace.go:171","msg":"trace[273796559] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"131.050824ms","start":"2026-05-29T05:09:55.763672Z","end":"2026-05-29T05:09:55.894723Z","steps":["trace[273796559] 'process raft request' (duration: 60.134327ms)","trace[273796559] 'compare' (duration: 70.588539ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:56.067867Z","caller":"traceutil/trace.go:171","msg":"trace[1153962891] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"299.079344ms","start":"2026-05-29T05:09:55.768747Z","end":"2026-05-29T05:09:56.067827Z","steps":["trace[1153962891] 'process raft request' (duration: 254.535665ms)","trace[1153962891] 'compare' (duration: 44.256652ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:56.067947Z","caller":"traceutil/trace.go:171","msg":"trace[1971684589] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"298.053698ms","start":"2026-05-29T05:09:55.769869Z","end":"2026-05-29T05:09:56.067923Z","steps":["trace[1971684589] 'process raft request' (duration: 297.904454ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:56.067868Z","caller":"traceutil/trace.go:171","msg":"trace[520442141] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1184; }","duration":"204.313818ms","start":"2026-05-29T05:09:55.863532Z","end":"2026-05-29T05:09:56.067846Z","steps":["trace[520442141] 'read index received' (duration: 159.870602ms)","trace[520442141] 'applied index is now lower than readState.Index' (duration: 44.442136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:09:56.068371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.81955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-29T05:09:56.068652Z","caller":"traceutil/trace.go:171","msg":"trace[2098348066] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1139; }","duration":"205.086597ms","start":"2026-05-29T05:09:55.863488Z","end":"2026-05-29T05:09:56.068574Z","steps":["trace[2098348066] 'agreement among raft nodes before linearized reading' (duration: 204.702697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:56.068716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.760409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-05-29T05:09:56.068806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.090914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363690"} {"level":"info","ts":"2026-05-29T05:09:56.068813Z","caller":"traceutil/trace.go:171","msg":"trace[1329409482] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1139; }","duration":"204.892783ms","start":"2026-05-29T05:09:55.863897Z","end":"2026-05-29T05:09:56.06879Z","steps":["trace[1329409482] 'agreement among raft nodes before linearized reading' (duration: 204.655557ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:56.068861Z","caller":"traceutil/trace.go:171","msg":"trace[512180889] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1139; }","duration":"197.171945ms","start":"2026-05-29T05:09:55.871675Z","end":"2026-05-29T05:09:56.068846Z","steps":["trace[512180889] 'agreement among raft nodes before linearized reading' (duration: 196.890058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:56.068856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.982602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-05-29T05:09:56.068998Z","caller":"traceutil/trace.go:171","msg":"trace[468443913] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1139; }","duration":"105.166276ms","start":"2026-05-29T05:09:55.963774Z","end":"2026-05-29T05:09:56.068941Z","steps":["trace[468443913] 'agreement among raft nodes before linearized reading' (duration: 104.787577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:56.069456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.32867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-05-29T05:09:56.070216Z","caller":"traceutil/trace.go:171","msg":"trace[1381371224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1139; }","duration":"106.320385ms","start":"2026-05-29T05:09:55.963865Z","end":"2026-05-29T05:09:56.070185Z","steps":["trace[1381371224] 'agreement among raft nodes before linearized reading' (duration: 105.209217ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:56.068463Z","caller":"traceutil/trace.go:171","msg":"trace[209105163] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"299.468713ms","start":"2026-05-29T05:09:55.768939Z","end":"2026-05-29T05:09:56.068408Z","steps":["trace[209105163] 'process raft request' (duration: 298.761646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:56.070493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:55.768933Z","time spent":"301.3609ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:09:59.535819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764672499,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:09:59.548296Z","caller":"traceutil/trace.go:171","msg":"trace[74191388] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"857.460247ms","start":"2026-05-29T05:09:58.69081Z","end":"2026-05-29T05:09:59.54827Z","steps":["trace[74191388] 'process raft request' (duration: 857.320564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:59.548487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:58.69079Z","time spent":"857.576999ms","remote":"127.0.0.1:42018","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-05-29T05:10:00.121561Z","caller":"traceutil/trace.go:171","msg":"trace[778206101] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"1.142129691s","start":"2026-05-29T05:09:58.97941Z","end":"2026-05-29T05:10:00.12154Z","steps":["trace[778206101] 'process raft request' (duration: 1.139919017s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.121708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:58.979381Z","time spent":"1.142273435s","remote":"127.0.0.1:42018","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-05-29T05:10:00.127221Z","caller":"traceutil/trace.go:171","msg":"trace[1360705264] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1201; }","duration":"1.091897684s","start":"2026-05-29T05:09:59.035295Z","end":"2026-05-29T05:10:00.127193Z","steps":["trace[1360705264] 'read index received' (duration: 512.882211ms)","trace[1360705264] 'applied index is now lower than readState.Index' (duration: 579.013973ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:00.127357Z","caller":"traceutil/trace.go:171","msg":"trace[278809212] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"794.731157ms","start":"2026-05-29T05:09:59.332557Z","end":"2026-05-29T05:10:00.127288Z","steps":["trace[278809212] 'process raft request' (duration: 794.474481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.127458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00790024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:00.127512Z","caller":"traceutil/trace.go:171","msg":"trace[992931238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"1.007993562s","start":"2026-05-29T05:09:59.119503Z","end":"2026-05-29T05:10:00.127496Z","steps":["trace[992931238] 'agreement among raft nodes before linearized reading' (duration: 1.007922951s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.127518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.332538Z","time spent":"794.895102ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:00.127535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089070997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:10:00.127592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.926121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:00.127592Z","caller":"traceutil/trace.go:171","msg":"trace[532979828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"1.089169798s","start":"2026-05-29T05:09:59.038406Z","end":"2026-05-29T05:10:00.127576Z","steps":["trace[532979828] 'agreement among raft nodes before linearized reading' (duration: 1.088981254s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:00.127615Z","caller":"traceutil/trace.go:171","msg":"trace[1995557681] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1156; }","duration":"443.988362ms","start":"2026-05-29T05:09:59.683618Z","end":"2026-05-29T05:10:00.127607Z","steps":["trace[1995557681] 'agreement among raft nodes before linearized reading' (duration: 443.953891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.127634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.683592Z","time spent":"444.037174ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:10:00.127643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.038389Z","time spent":"1.089240981s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:10:00.127651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070350913s","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-05-29T05:10:00.127733Z","caller":"traceutil/trace.go:171","msg":"trace[1745958608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"1.070468995s","start":"2026-05-29T05:09:59.057234Z","end":"2026-05-29T05:10:00.127703Z","steps":["trace[1745958608] 'agreement among raft nodes before linearized reading' (duration: 1.07024573s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.127774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.057213Z","time spent":"1.070552307s","remote":"127.0.0.1:42018","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-05-29T05:10:00.127854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085432298s","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-05-29T05:10:00.127539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.119486Z","time spent":"1.008047894s","remote":"127.0.0.1:41758","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-05-29T05:10:00.127897Z","caller":"traceutil/trace.go:171","msg":"trace[943828909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1156; }","duration":"1.085488649s","start":"2026-05-29T05:09:59.042395Z","end":"2026-05-29T05:10:00.127883Z","steps":["trace[943828909] 'agreement among raft nodes before linearized reading' (duration: 1.085370266s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.127959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.042386Z","time spent":"1.08554097s","remote":"127.0.0.1:42018","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-05-29T05:10:00.128186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09211255s","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-05-29T05:10:00.128273Z","caller":"traceutil/trace.go:171","msg":"trace[469608094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1156; }","duration":"1.093015332s","start":"2026-05-29T05:09:59.035239Z","end":"2026-05-29T05:10:00.128254Z","steps":["trace[469608094] 'agreement among raft nodes before linearized reading' (duration: 1.092069419s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:00.128316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:09:59.035219Z","time spent":"1.093085493s","remote":"127.0.0.1:42018","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-05-29T05:10:14.337749Z","caller":"traceutil/trace.go:171","msg":"trace[1306476303] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"124.004385ms","start":"2026-05-29T05:10:14.213689Z","end":"2026-05-29T05:10:14.337693Z","steps":["trace[1306476303] 'read index received' (duration: 123.81606ms)","trace[1306476303] 'applied index is now lower than readState.Index' (duration: 187.365µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:14.337822Z","caller":"traceutil/trace.go:171","msg":"trace[1213761439] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"169.155698ms","start":"2026-05-29T05:10:14.168624Z","end":"2026-05-29T05:10:14.337779Z","steps":["trace[1213761439] 'process raft request' (duration: 168.866831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:14.33798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.277251ms","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-05-29T05:10:14.338018Z","caller":"traceutil/trace.go:171","msg":"trace[1699324734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"124.362443ms","start":"2026-05-29T05:10:14.213646Z","end":"2026-05-29T05:10:14.338008Z","steps":["trace[1699324734] 'agreement among raft nodes before linearized reading' (duration: 124.22501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:14.338017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.741635ms","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-05-29T05:10:14.338049Z","caller":"traceutil/trace.go:171","msg":"trace[1676549051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"106.780136ms","start":"2026-05-29T05:10:14.231259Z","end":"2026-05-29T05:10:14.33804Z","steps":["trace[1676549051] 'agreement among raft nodes before linearized reading' (duration: 106.654913ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:14.853662Z","caller":"traceutil/trace.go:171","msg":"trace[56590510] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"511.051198ms","start":"2026-05-29T05:10:14.342583Z","end":"2026-05-29T05:10:14.853634Z","steps":["trace[56590510] 'process raft request' (duration: 416.661622ms)","trace[56590510] 'compare' (duration: 94.239222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:14.853802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:14.342561Z","time spent":"511.16949ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:14.875124Z","caller":"traceutil/trace.go:171","msg":"trace[1797312660] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"532.399764ms","start":"2026-05-29T05:10:14.342707Z","end":"2026-05-29T05:10:14.875107Z","steps":["trace[1797312660] 'process raft request' (duration: 532.352023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:14.875238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:14.342691Z","time spent":"532.486066ms","remote":"127.0.0.1:42018","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-05-29T05:10:14.875261Z","caller":"traceutil/trace.go:171","msg":"trace[1472852523] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"532.167169ms","start":"2026-05-29T05:10:14.343078Z","end":"2026-05-29T05:10:14.875245Z","steps":["trace[1472852523] 'process raft request' (duration: 532.005224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:14.875409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:14.343068Z","time spent":"532.268491ms","remote":"127.0.0.1:42018","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-05-29T05:10:14.877136Z","caller":"traceutil/trace.go:171","msg":"trace[1446216335] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"532.518717ms","start":"2026-05-29T05:10:14.342631Z","end":"2026-05-29T05:10:14.875149Z","steps":["trace[1446216335] 'process raft request' (duration: 532.343473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:14.877243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:14.342609Z","time spent":"534.590677ms","remote":"127.0.0.1:42018","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-05-29T05:10:16.18333Z","caller":"traceutil/trace.go:171","msg":"trace[1664928696] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"457.007628ms","start":"2026-05-29T05:10:15.726298Z","end":"2026-05-29T05:10:16.183306Z","steps":["trace[1664928696] 'read index received' (duration: 456.931786ms)","trace[1664928696] 'applied index is now lower than readState.Index' (duration: 74.832µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:16.183362Z","caller":"traceutil/trace.go:171","msg":"trace[1908427427] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"520.812073ms","start":"2026-05-29T05:10:15.662529Z","end":"2026-05-29T05:10:16.183341Z","steps":["trace[1908427427] 'process raft request' (duration: 520.576567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:16.183451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.138911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:10:16.183489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:15.662514Z","time spent":"520.918915ms","remote":"127.0.0.1:42018","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-05-29T05:10:16.183481Z","caller":"traceutil/trace.go:171","msg":"trace[452639190] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1207; }","duration":"457.220393ms","start":"2026-05-29T05:10:15.726252Z","end":"2026-05-29T05:10:16.183472Z","steps":["trace[452639190] 'agreement among raft nodes before linearized reading' (duration: 457.150872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:16.183543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:15.726204Z","time spent":"457.329857ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:10:16.239006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.694638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:16.239084Z","caller":"traceutil/trace.go:171","msg":"trace[1742734118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"119.816811ms","start":"2026-05-29T05:10:16.119249Z","end":"2026-05-29T05:10:16.239066Z","steps":["trace[1742734118] 'agreement among raft nodes before linearized reading' (duration: 119.677487ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:16.239312Z","caller":"traceutil/trace.go:171","msg":"trace[87823774] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"346.845249ms","start":"2026-05-29T05:10:15.892455Z","end":"2026-05-29T05:10:16.2393Z","steps":["trace[87823774] 'process raft request' (duration: 346.260005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:16.239392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:15.89243Z","time spent":"346.905681ms","remote":"127.0.0.1:42018","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-05-29T05:10:17.047174Z","caller":"traceutil/trace.go:171","msg":"trace[252592819] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"119.898074ms","start":"2026-05-29T05:10:16.927227Z","end":"2026-05-29T05:10:17.047125Z","steps":["trace[252592819] 'process raft request' (duration: 107.055943ms)","trace[252592819] 'compare' (duration: 12.619366ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:17.04716Z","caller":"traceutil/trace.go:171","msg":"trace[671724632] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"119.350931ms","start":"2026-05-29T05:10:16.927781Z","end":"2026-05-29T05:10:17.047132Z","steps":["trace[671724632] 'process raft request' (duration: 119.268479ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:18.857707Z","caller":"traceutil/trace.go:171","msg":"trace[2042722406] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"666.251436ms","start":"2026-05-29T05:10:18.19143Z","end":"2026-05-29T05:10:18.857682Z","steps":["trace[2042722406] 'process raft request' (duration: 666.046241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:18.857899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:18.19141Z","time spent":"666.412439ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:19.453506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.001202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:10:19.453629Z","caller":"traceutil/trace.go:171","msg":"trace[2021756145] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"525.746213ms","start":"2026-05-29T05:10:18.927866Z","end":"2026-05-29T05:10:19.453612Z","steps":["trace[2021756145] 'read index received' (duration: 323.458434ms)","trace[2021756145] 'applied index is now lower than readState.Index' (duration: 202.286549ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:19.453663Z","caller":"traceutil/trace.go:171","msg":"trace[278828363] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"1.013755872s","start":"2026-05-29T05:10:18.439885Z","end":"2026-05-29T05:10:19.453641Z","steps":["trace[278828363] 'process raft request' (duration: 811.543095ms)","trace[278828363] 'compare' (duration: 201.758166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:19.45375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:18.439859Z","time spent":"1.013847495s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:19.453836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.954518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:10:19.453907Z","caller":"traceutil/trace.go:171","msg":"trace[2047441167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"526.059341ms","start":"2026-05-29T05:10:18.927829Z","end":"2026-05-29T05:10:19.453889Z","steps":["trace[2047441167] 'agreement among raft nodes before linearized reading' (duration: 525.852096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:19.453973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:18.927815Z","time spent":"526.145533ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:10:19.723681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.352989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:10:19.723792Z","caller":"traceutil/trace.go:171","msg":"trace[1296067056] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"270.117382ms","start":"2026-05-29T05:10:19.453656Z","end":"2026-05-29T05:10:19.723773Z","steps":["trace[1296067056] 'read index received' (duration: 20.686941ms)","trace[1296067056] 'applied index is now lower than readState.Index' (duration: 249.42882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:19.724104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.183297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:10:19.724139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.737593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:10:19.72415Z","caller":"traceutil/trace.go:171","msg":"trace[430923355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"605.261058ms","start":"2026-05-29T05:10:19.11888Z","end":"2026-05-29T05:10:19.724141Z","steps":["trace[430923355] 'agreement among raft nodes before linearized reading' (duration: 605.190247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:19.724181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.118866Z","time spent":"605.30928ms","remote":"127.0.0.1:41758","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-05-29T05:10:19.72418Z","caller":"traceutil/trace.go:171","msg":"trace[1893177809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"672.793564ms","start":"2026-05-29T05:10:19.051376Z","end":"2026-05-29T05:10:19.72417Z","steps":["trace[1893177809] 'agreement among raft nodes before linearized reading' (duration: 672.650401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:19.724174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.563691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:10:19.724213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.051372Z","time spent":"672.834135ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:10:19.724246Z","caller":"traceutil/trace.go:171","msg":"trace[1571328900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"744.674484ms","start":"2026-05-29T05:10:18.979553Z","end":"2026-05-29T05:10:19.724228Z","steps":["trace[1571328900] 'agreement among raft nodes before linearized reading' (duration: 744.446289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:19.724293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:18.979539Z","time spent":"744.745996ms","remote":"127.0.0.1:42018","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-05-29T05:10:19.724105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.722703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:10:19.724822Z","caller":"traceutil/trace.go:171","msg":"trace[1507020859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"673.448041ms","start":"2026-05-29T05:10:19.051359Z","end":"2026-05-29T05:10:19.724807Z","steps":["trace[1507020859] 'agreement among raft nodes before linearized reading' (duration: 672.62103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:19.724875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.051307Z","time spent":"673.550793ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:10:20.107566Z","caller":"traceutil/trace.go:171","msg":"trace[1699034550] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"650.435382ms","start":"2026-05-29T05:10:19.457105Z","end":"2026-05-29T05:10:20.107541Z","steps":["trace[1699034550] 'process raft request' (duration: 650.298559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.107743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.457088Z","time spent":"650.545925ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:20.280773Z","caller":"traceutil/trace.go:171","msg":"trace[364685137] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"760.512229ms","start":"2026-05-29T05:10:19.520223Z","end":"2026-05-29T05:10:20.280735Z","steps":["trace[364685137] 'process raft request' (duration: 686.660011ms)","trace[364685137] 'compare' (duration: 73.595132ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:20.280854Z","caller":"traceutil/trace.go:171","msg":"trace[1434505423] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"553.614149ms","start":"2026-05-29T05:10:19.727208Z","end":"2026-05-29T05:10:20.280823Z","steps":["trace[1434505423] 'process raft request' (duration: 553.455385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.280915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.520196Z","time spent":"760.655742ms","remote":"127.0.0.1:41820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:10:20.281045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.727192Z","time spent":"553.741872ms","remote":"127.0.0.1:42018","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-05-29T05:10:20.281262Z","caller":"traceutil/trace.go:171","msg":"trace[1502682940] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"553.549576ms","start":"2026-05-29T05:10:19.727693Z","end":"2026-05-29T05:10:20.281242Z","steps":["trace[1502682940] 'process raft request' (duration: 553.069244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.281383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.727675Z","time spent":"553.644948ms","remote":"127.0.0.1:42018","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-05-29T05:10:20.281396Z","caller":"traceutil/trace.go:171","msg":"trace[1788803107] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"557.567023ms","start":"2026-05-29T05:10:19.723819Z","end":"2026-05-29T05:10:20.281386Z","steps":["trace[1788803107] 'read index received' (duration: 383.60497ms)","trace[1788803107] 'applied index is now lower than readState.Index' (duration: 173.961433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:20.281513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.884558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-05-29T05:10:20.281546Z","caller":"traceutil/trace.go:171","msg":"trace[1630182232] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1221; }","duration":"644.95649ms","start":"2026-05-29T05:10:19.63658Z","end":"2026-05-29T05:10:20.281537Z","steps":["trace[1630182232] 'agreement among raft nodes before linearized reading' (duration: 644.840667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.281569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.636563Z","time spent":"645.001202ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-29T05:10:20.281564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.837135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:20.281685Z","caller":"traceutil/trace.go:171","msg":"trace[1654527303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"556.976239ms","start":"2026-05-29T05:10:19.724691Z","end":"2026-05-29T05:10:20.281667Z","steps":["trace[1654527303] 'agreement among raft nodes before linearized reading' (duration: 556.829606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.281747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.724678Z","time spent":"557.054251ms","remote":"127.0.0.1:41774","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-05-29T05:10:20.281792Z","caller":"traceutil/trace.go:171","msg":"trace[1551537115] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"553.72627ms","start":"2026-05-29T05:10:19.728057Z","end":"2026-05-29T05:10:20.281783Z","steps":["trace[1551537115] 'process raft request' (duration: 553.108855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:20.281839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:19.728049Z","time spent":"553.760931ms","remote":"127.0.0.1:42018","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-05-29T05:10:20.478266Z","caller":"traceutil/trace.go:171","msg":"trace[1399447141] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"103.823434ms","start":"2026-05-29T05:10:20.37442Z","end":"2026-05-29T05:10:20.478243Z","steps":["trace[1399447141] 'process raft request' (duration: 18.697172ms)","trace[1399447141] 'compare' (duration: 85.01518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:21.362713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764672664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:10:21.863329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103385764672664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:10:21.871262Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111507359s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T05:10:21.917932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280783999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:10:21.918037Z","caller":"traceutil/trace.go:171","msg":"trace[44384695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1223; }","duration":"1.280940804s","start":"2026-05-29T05:10:20.637072Z","end":"2026-05-29T05:10:21.918013Z","steps":["trace[44384695] 'count revisions from in-memory index tree' (duration: 1.280659326s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:21.918133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:20.637027Z","time spent":"1.281059757s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:10:21.91852Z","caller":"traceutil/trace.go:171","msg":"trace[1472193052] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.158843877s","start":"2026-05-29T05:10:20.759655Z","end":"2026-05-29T05:10:21.918499Z","steps":["trace[1472193052] 'process raft request' (duration: 1.111892199s)","trace[1472193052] 'compare' (duration: 46.011514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:21.918646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:20.759637Z","time spent":"1.158940398s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:23.118441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999097015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T05:10:23.11854Z","caller":"traceutil/trace.go:171","msg":"trace[647737300] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999256149s","start":"2026-05-29T05:10:21.119264Z","end":"2026-05-29T05:10:23.11852Z","steps":["trace[647737300] 'agreement among raft nodes before linearized reading' (duration: 1.999136536s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.11859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:21.119243Z","time spent":"1.999336281s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:10:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:10:23.423054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.773444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:10:23.423246Z","caller":"traceutil/trace.go:171","msg":"trace[176079236] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"301.434611ms","start":"2026-05-29T05:10:23.121795Z","end":"2026-05-29T05:10:23.42323Z","steps":["trace[176079236] 'process raft request' (duration: 301.360189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.423304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.121771Z","time spent":"301.506303ms","remote":"127.0.0.1:41820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:10:23.423486Z","caller":"traceutil/trace.go:171","msg":"trace[1728687940] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"2.561710762s","start":"2026-05-29T05:10:20.861764Z","end":"2026-05-29T05:10:23.423475Z","steps":["trace[1728687940] 'read index received' (duration: 1.009845648s)","trace[1728687940] 'applied index is now lower than readState.Index' (duration: 1.551864114s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:23.423786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.562026319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T05:10:23.423817Z","caller":"traceutil/trace.go:171","msg":"trace[1116793585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"2.562095041s","start":"2026-05-29T05:10:20.861712Z","end":"2026-05-29T05:10:23.423807Z","steps":["trace[1116793585] 'agreement among raft nodes before linearized reading' (duration: 2.561934197s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.423845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:20.861692Z","time spent":"2.562146172s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:10:23.423858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311613005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T05:10:23.423924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.194023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:23.423951Z","caller":"traceutil/trace.go:171","msg":"trace[1488914746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"296.250665ms","start":"2026-05-29T05:10:23.127694Z","end":"2026-05-29T05:10:23.423945Z","steps":["trace[1488914746] 'agreement among raft nodes before linearized reading' (duration: 296.219734ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:23.423938Z","caller":"traceutil/trace.go:171","msg":"trace[1061746213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"1.311743418s","start":"2026-05-29T05:10:22.112177Z","end":"2026-05-29T05:10:23.423921Z","steps":["trace[1061746213] 'agreement among raft nodes before linearized reading' (duration: 1.311528083s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.423988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:22.11216Z","time spent":"1.311815321s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:10:23.424138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139194548s","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-05-29T05:10:23.424153Z","caller":"traceutil/trace.go:171","msg":"trace[91921621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"1.13924495s","start":"2026-05-29T05:10:22.284903Z","end":"2026-05-29T05:10:23.424148Z","steps":["trace[91921621] 'agreement among raft nodes before linearized reading' (duration: 1.139203839s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.423899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137929589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:10:23.424166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:22.284875Z","time spent":"1.139287111s","remote":"127.0.0.1:42018","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-05-29T05:10:23.424162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138257217s","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-05-29T05:10:23.424201Z","caller":"traceutil/trace.go:171","msg":"trace[1499281877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"1.138321528s","start":"2026-05-29T05:10:22.285866Z","end":"2026-05-29T05:10:23.424188Z","steps":["trace[1499281877] 'agreement among raft nodes before linearized reading' (duration: 1.138211615s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:23.424205Z","caller":"traceutil/trace.go:171","msg":"trace[1953843311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"1.138237185s","start":"2026-05-29T05:10:22.285943Z","end":"2026-05-29T05:10:23.42418Z","steps":["trace[1953843311] 'agreement among raft nodes before linearized reading' (duration: 1.137911588s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.424236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:22.285851Z","time spent":"1.1383754s","remote":"127.0.0.1:42018","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-05-29T05:10:23.424293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:22.285913Z","time spent":"1.138325898s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:10:23.42329Z","caller":"traceutil/trace.go:171","msg":"trace[43161423] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.965253526s","start":"2026-05-29T05:10:21.457991Z","end":"2026-05-29T05:10:23.423244Z","steps":["trace[43161423] 'process raft request' (duration: 1.406210706s)","trace[43161423] 'compare' (duration: 558.572029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:23.425053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:21.457975Z","time spent":"1.966989347s","remote":"127.0.0.1:42018","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-05-29T05:10:23.863407Z","caller":"traceutil/trace.go:171","msg":"trace[323214451] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"435.455145ms","start":"2026-05-29T05:10:23.427918Z","end":"2026-05-29T05:10:23.863373Z","steps":["trace[323214451] 'process raft request' (duration: 350.13909ms)","trace[323214451] 'compare' (duration: 85.147561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:23.863574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.427904Z","time spent":"435.587159ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:23.874086Z","caller":"traceutil/trace.go:171","msg":"trace[1061007700] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"446.052503ms","start":"2026-05-29T05:10:23.428002Z","end":"2026-05-29T05:10:23.874054Z","steps":["trace[1061007700] 'process raft request' (duration: 445.888599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.874238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.427964Z","time spent":"446.194386ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:23.874246Z","caller":"traceutil/trace.go:171","msg":"trace[1575533013] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"445.0741ms","start":"2026-05-29T05:10:23.429153Z","end":"2026-05-29T05:10:23.874227Z","steps":["trace[1575533013] 'process raft request' (duration: 444.854384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.874347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.429136Z","time spent":"445.157611ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:10:23.875714Z","caller":"traceutil/trace.go:171","msg":"trace[2048267572] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"445.96262ms","start":"2026-05-29T05:10:23.42972Z","end":"2026-05-29T05:10:23.875683Z","steps":["trace[2048267572] 'process raft request' (duration: 444.425164ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:23.875914Z","caller":"traceutil/trace.go:171","msg":"trace[1301461233] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1279; }","duration":"128.216224ms","start":"2026-05-29T05:10:23.747674Z","end":"2026-05-29T05:10:23.87589Z","steps":["trace[1301461233] 'read index received' (duration: 30.392916ms)","trace[1301461233] 'applied index is now lower than readState.Index' (duration: 97.822278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:23.876063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.379239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:10:23.876101Z","caller":"traceutil/trace.go:171","msg":"trace[594553457] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1231; }","duration":"128.425201ms","start":"2026-05-29T05:10:23.747666Z","end":"2026-05-29T05:10:23.876091Z","steps":["trace[594553457] 'agreement among raft nodes before linearized reading' (duration: 128.290937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.876261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.429706Z","time spent":"446.098483ms","remote":"127.0.0.1:42018","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-05-29T05:10:23.876384Z","caller":"traceutil/trace.go:171","msg":"trace[1817327834] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"444.105686ms","start":"2026-05-29T05:10:23.432261Z","end":"2026-05-29T05:10:23.876367Z","steps":["trace[1817327834] 'process raft request' (duration: 443.312746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:23.87649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:23.432238Z","time spent":"444.191058ms","remote":"127.0.0.1:42018","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-05-29T05:10:31.356976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380537527s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:10:31.357905Z","caller":"traceutil/trace.go:171","msg":"trace[759066528] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"1.462712311s","start":"2026-05-29T05:10:29.895169Z","end":"2026-05-29T05:10:31.357881Z","steps":["trace[759066528] 'read index received' (duration: 81.195512ms)","trace[759066528] 'applied index is now lower than readState.Index' (duration: 1.381515219s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:31.358008Z","caller":"traceutil/trace.go:171","msg":"trace[105457988] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.907424308s","start":"2026-05-29T05:10:29.450571Z","end":"2026-05-29T05:10:31.357995Z","steps":["trace[105457988] 'process raft request' (duration: 1.907134351s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.3581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.450546Z","time spent":"1.907486929s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:10:31.358284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238404949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:10:31.358268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459440417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:10:31.35834Z","caller":"traceutil/trace.go:171","msg":"trace[825663890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1276; }","duration":"1.459557439s","start":"2026-05-29T05:10:29.898763Z","end":"2026-05-29T05:10:31.35832Z","steps":["trace[825663890] 'agreement among raft nodes before linearized reading' (duration: 1.459338914s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:31.358321Z","caller":"traceutil/trace.go:171","msg":"trace[59588098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"1.238489061s","start":"2026-05-29T05:10:30.119822Z","end":"2026-05-29T05:10:31.358311Z","steps":["trace[59588098] 'agreement among raft nodes before linearized reading' (duration: 1.23842774s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.358379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.898744Z","time spent":"1.45962629s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:10:31.358383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:30.119797Z","time spent":"1.238579084s","remote":"127.0.0.1:41758","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-05-29T05:10:31.358468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463300035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T05:10:31.358479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.422777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:10:31.358538Z","caller":"traceutil/trace.go:171","msg":"trace[1197322068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"1.463430128s","start":"2026-05-29T05:10:29.895087Z","end":"2026-05-29T05:10:31.358517Z","steps":["trace[1197322068] 'agreement among raft nodes before linearized reading' (duration: 1.463283154s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.358529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457754037s","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-05-29T05:10:31.358558Z","caller":"traceutil/trace.go:171","msg":"trace[1813582859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1276; }","duration":"117.556611ms","start":"2026-05-29T05:10:31.24098Z","end":"2026-05-29T05:10:31.358537Z","steps":["trace[1813582859] 'agreement among raft nodes before linearized reading' (duration: 117.410617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.358587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.895063Z","time spent":"1.463508311s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:10:31.358592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456980661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:10:31.358603Z","caller":"traceutil/trace.go:171","msg":"trace[1695385108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"1.45785588s","start":"2026-05-29T05:10:29.900727Z","end":"2026-05-29T05:10:31.358583Z","steps":["trace[1695385108] 'agreement among raft nodes before linearized reading' (duration: 1.457651295s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.358501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457655095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:10:31.358631Z","caller":"traceutil/trace.go:171","msg":"trace[1485757946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"1.457049932s","start":"2026-05-29T05:10:29.901567Z","end":"2026-05-29T05:10:31.358617Z","steps":["trace[1485757946] 'agreement among raft nodes before linearized reading' (duration: 1.45696296s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.358646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.900718Z","time spent":"1.457919383s","remote":"127.0.0.1:42018","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-05-29T05:10:31.358666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.901554Z","time spent":"1.457105274s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:10:31.358663Z","caller":"traceutil/trace.go:171","msg":"trace[2065954765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1276; }","duration":"1.457847061s","start":"2026-05-29T05:10:29.900804Z","end":"2026-05-29T05:10:31.358651Z","steps":["trace[2065954765] 'agreement among raft nodes before linearized reading' (duration: 1.457545383s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:10:31.3587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:10:29.900792Z","time spent":"1.457897622s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:10:45.518978Z","caller":"traceutil/trace.go:171","msg":"trace[605568733] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"102.590055ms","start":"2026-05-29T05:10:45.416361Z","end":"2026-05-29T05:10:45.518951Z","steps":["trace[605568733] 'read index received' (duration: 102.38617ms)","trace[605568733] 'applied index is now lower than readState.Index' (duration: 202.995µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:10:45.519242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.857531ms","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-05-29T05:10:45.519275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.154333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:10:45.519681Z","caller":"traceutil/trace.go:171","msg":"trace[1749815038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"102.969464ms","start":"2026-05-29T05:10:45.416328Z","end":"2026-05-29T05:10:45.519298Z","steps":["trace[1749815038] 'agreement among raft nodes before linearized reading' (duration: 102.713298ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:45.519776Z","caller":"traceutil/trace.go:171","msg":"trace[402368724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"101.269515ms","start":"2026-05-29T05:10:45.41807Z","end":"2026-05-29T05:10:45.519339Z","steps":["trace[402368724] 'agreement among raft nodes before linearized reading' (duration: 101.03894ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:45.519548Z","caller":"traceutil/trace.go:171","msg":"trace[1977027236] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"116.932426ms","start":"2026-05-29T05:10:45.402135Z","end":"2026-05-29T05:10:45.519068Z","steps":["trace[1977027236] 'process raft request' (duration: 116.66395ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:45.647293Z","caller":"traceutil/trace.go:171","msg":"trace[1366752127] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"123.522678ms","start":"2026-05-29T05:10:45.523743Z","end":"2026-05-29T05:10:45.647265Z","steps":["trace[1366752127] 'process raft request' (duration: 123.479217ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:45.647568Z","caller":"traceutil/trace.go:171","msg":"trace[320176306] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"124.711196ms","start":"2026-05-29T05:10:45.522779Z","end":"2026-05-29T05:10:45.64749Z","steps":["trace[320176306] 'process raft request' (duration: 86.156156ms)","trace[320176306] 'compare' (duration: 37.854834ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:10:45.647587Z","caller":"traceutil/trace.go:171","msg":"trace[864255094] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"124.198924ms","start":"2026-05-29T05:10:45.523356Z","end":"2026-05-29T05:10:45.647555Z","steps":["trace[864255094] 'process raft request' (duration: 123.834116ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:45.647612Z","caller":"traceutil/trace.go:171","msg":"trace[2104187007] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"124.400999ms","start":"2026-05-29T05:10:45.523183Z","end":"2026-05-29T05:10:45.647584Z","steps":["trace[2104187007] 'process raft request' (duration: 123.963499ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:10:46.184857Z","caller":"traceutil/trace.go:171","msg":"trace[784249752] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"124.703796ms","start":"2026-05-29T05:10:46.060126Z","end":"2026-05-29T05:10:46.18483Z","steps":["trace[784249752] 'process raft request' (duration: 124.465101ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:37.928624Z","caller":"traceutil/trace.go:171","msg":"trace[341765028] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"149.974263ms","start":"2026-05-29T05:11:37.778615Z","end":"2026-05-29T05:11:37.928589Z","steps":["trace[341765028] 'process raft request' (duration: 149.827749ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:37.983161Z","caller":"traceutil/trace.go:171","msg":"trace[779018410] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"132.635953ms","start":"2026-05-29T05:11:37.850481Z","end":"2026-05-29T05:11:37.983117Z","steps":["trace[779018410] 'process raft request' (duration: 130.330136ms)"],"step_count":1}