{"level":"warn","ts":"2026-08-11T08:15:27.966268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T08:15:27.966357Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.127:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.127:2380","--initial-cluster=instance=https://199.19.213.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.127:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T08:15:27.966424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T08:15:27.966435Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-08-11T08:15:27.966458Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T08:15:27.967322Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"]} {"level":"info","ts":"2026-08-11T08:15:27.967444Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.127:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T08:15:27.970072Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.451078ms"} {"level":"info","ts":"2026-08-11T08:15:27.976076Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"975f045ff4f5c0fb","cluster-id":"b3df79a2daf9b6ed"} {"level":"info","ts":"2026-08-11T08:15:27.976141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T08:15:27.976162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 0"} {"level":"info","ts":"2026-08-11T08:15:27.97618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 975f045ff4f5c0fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T08:15:27.976193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 1"} {"level":"info","ts":"2026-08-11T08:15:27.976238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"warn","ts":"2026-08-11T08:15:27.983618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T08:15:27.9848Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T08:15:27.985797Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-11T08:15:27.988386Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"975f045ff4f5c0fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T08:15:27.988839Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T08:15:27.988821Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"975f045ff4f5c0fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T08:15:27.988897Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T08:15:27.988931Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T08:15:27.98968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"info","ts":"2026-08-11T08:15:27.98978Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","added-peer-id":"975f045ff4f5c0fb","added-peer-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-08-11T08:15:27.992521Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T08:15:27.992668Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-08-11T08:15:27.992717Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-08-11T08:15:27.992867Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"975f045ff4f5c0fb","initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T08:15:27.992921Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T08:15:28.577309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T08:15:28.577379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T08:15:28.577529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgPreVoteResp from 975f045ff4f5c0fb at term 1"} {"level":"info","ts":"2026-08-11T08:15:28.577595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became candidate at term 2"} {"level":"info","ts":"2026-08-11T08:15:28.577636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgVoteResp from 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-08-11T08:15:28.5777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became leader at term 2"} {"level":"info","ts":"2026-08-11T08:15:28.577783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 975f045ff4f5c0fb elected leader 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-08-11T08:15:28.579105Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"975f045ff4f5c0fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.127:2379]}","request-path":"/0/members/975f045ff4f5c0fb/attributes","cluster-id":"b3df79a2daf9b6ed","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T08:15:28.579239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T08:15:28.579294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T08:15:28.57955Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:15:28.580083Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T08:15:28.58012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T08:15:28.581087Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:15:28.58116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:15:28.581191Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:15:28.582439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T08:15:28.58278Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.127:2379"} {"level":"info","ts":"2026-08-11T08:15:48.835688Z","caller":"traceutil/trace.go:171","msg":"trace[1900076744] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"138.166187ms","start":"2026-08-11T08:15:48.697499Z","end":"2026-08-11T08:15:48.835665Z","steps":["trace[1900076744] 'read index received' (duration: 137.223167ms)","trace[1900076744] 'applied index is now lower than readState.Index' (duration: 941.86µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:15:48.836178Z","caller":"traceutil/trace.go:171","msg":"trace[351304184] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"558.961444ms","start":"2026-08-11T08:15:48.277173Z","end":"2026-08-11T08:15:48.836134Z","steps":["trace[351304184] 'process raft request' (duration: 557.607786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:15:48.836222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.692717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T08:15:48.836384Z","caller":"traceutil/trace.go:171","msg":"trace[1651561727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"138.914812ms","start":"2026-08-11T08:15:48.697438Z","end":"2026-08-11T08:15:48.836353Z","steps":["trace[1651561727] 'agreement among raft nodes before linearized reading' (duration: 138.624946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:15:48.837211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:15:48.27715Z","time spent":"559.167677ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:22.75673Z","caller":"traceutil/trace.go:171","msg":"trace[1303321732] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"107.798014ms","start":"2026-08-11T08:16:22.648892Z","end":"2026-08-11T08:16:22.75669Z","steps":["trace[1303321732] 'process raft request' (duration: 73.639645ms)","trace[1303321732] 'compare' (duration: 33.935175ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:22.756915Z","caller":"traceutil/trace.go:171","msg":"trace[1172659444] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"105.884176ms","start":"2026-08-11T08:16:22.650892Z","end":"2026-08-11T08:16:22.756776Z","steps":["trace[1172659444] 'process raft request' (duration: 105.751384ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:22.756741Z","caller":"traceutil/trace.go:171","msg":"trace[634769946] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"105.815945ms","start":"2026-08-11T08:16:22.650893Z","end":"2026-08-11T08:16:22.756709Z","steps":["trace[634769946] 'process raft request' (duration: 105.694862ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:22.756954Z","caller":"traceutil/trace.go:171","msg":"trace[765843038] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"105.918846ms","start":"2026-08-11T08:16:22.651019Z","end":"2026-08-11T08:16:22.756938Z","steps":["trace[765843038] 'process raft request' (duration: 105.656111ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:26.32601Z","caller":"traceutil/trace.go:171","msg":"trace[36595746] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"131.160136ms","start":"2026-08-11T08:16:26.194832Z","end":"2026-08-11T08:16:26.325992Z","steps":["trace[36595746] 'process raft request' (duration: 131.040533ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:35.261729Z","caller":"traceutil/trace.go:171","msg":"trace[1872703593] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"155.047557ms","start":"2026-08-11T08:16:35.106628Z","end":"2026-08-11T08:16:35.261676Z","steps":["trace[1872703593] 'process raft request' (duration: 154.789902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:35.261936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.840332ms","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-08-11T08:16:35.262011Z","caller":"traceutil/trace.go:171","msg":"trace[62666979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"153.988065ms","start":"2026-08-11T08:16:35.108006Z","end":"2026-08-11T08:16:35.261994Z","steps":["trace[62666979] 'agreement among raft nodes before linearized reading' (duration: 153.766621ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:35.261644Z","caller":"traceutil/trace.go:171","msg":"trace[1902811705] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"153.578698ms","start":"2026-08-11T08:16:35.108032Z","end":"2026-08-11T08:16:35.261611Z","steps":["trace[1902811705] 'read index received' (duration: 153.361964ms)","trace[1902811705] 'applied index is now lower than readState.Index' (duration: 215.354µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:35.377476Z","caller":"traceutil/trace.go:171","msg":"trace[197192054] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"109.269743ms","start":"2026-08-11T08:16:35.268183Z","end":"2026-08-11T08:16:35.377453Z","steps":["trace[197192054] 'process raft request' (duration: 109.225092ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:35.377605Z","caller":"traceutil/trace.go:171","msg":"trace[1080413207] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"110.746723ms","start":"2026-08-11T08:16:35.266847Z","end":"2026-08-11T08:16:35.377593Z","steps":["trace[1080413207] 'process raft request' (duration: 104.038018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:36.713679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.41113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:16:36.714105Z","caller":"traceutil/trace.go:171","msg":"trace[125826825] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"471.186933ms","start":"2026-08-11T08:16:36.242879Z","end":"2026-08-11T08:16:36.714066Z","steps":["trace[125826825] 'process raft request' (duration: 470.914328ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:36.714129Z","caller":"traceutil/trace.go:171","msg":"trace[1123503163] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"351.093411ms","start":"2026-08-11T08:16:36.363018Z","end":"2026-08-11T08:16:36.714112Z","steps":["trace[1123503163] 'read index received' (duration: 104.038628ms)","trace[1123503163] 'applied index is now lower than readState.Index' (duration: 247.053633ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:36.714092Z","caller":"traceutil/trace.go:171","msg":"trace[13356935] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"491.33389ms","start":"2026-08-11T08:16:36.222676Z","end":"2026-08-11T08:16:36.71401Z","steps":["trace[13356935] 'process raft request' (duration: 244.372089ms)","trace[13356935] 'compare' (duration: 246.266267ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:36.714196Z","caller":"traceutil/trace.go:171","msg":"trace[486950891] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"446.773531ms","start":"2026-08-11T08:16:36.267365Z","end":"2026-08-11T08:16:36.714139Z","steps":["trace[486950891] 'process raft request' (duration: 446.583918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:36.714353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:36.22265Z","time spent":"491.534213ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:36.714389Z","caller":"traceutil/trace.go:171","msg":"trace[1912683882] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"359.394339ms","start":"2026-08-11T08:16:36.354967Z","end":"2026-08-11T08:16:36.714361Z","steps":["trace[1912683882] 'process raft request' (duration: 359.083782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:36.714452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.433708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T08:16:36.714481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:36.354932Z","time spent":"359.517802ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T08:16:36.71449Z","caller":"traceutil/trace.go:171","msg":"trace[1138910902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"351.50589ms","start":"2026-08-11T08:16:36.362972Z","end":"2026-08-11T08:16:36.714478Z","steps":["trace[1138910902] 'agreement among raft nodes before linearized reading' (duration: 351.196213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:36.714528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:36.362954Z","time spent":"351.566701ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T08:16:36.714264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:36.242843Z","time spent":"471.335616ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:16:36.714885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:36.267334Z","time spent":"446.972786ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:43.399683Z","caller":"traceutil/trace.go:171","msg":"trace[1759575010] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"323.438783ms","start":"2026-08-11T08:16:43.076211Z","end":"2026-08-11T08:16:43.39965Z","steps":["trace[1759575010] 'read index received' (duration: 322.354512ms)","trace[1759575010] 'applied index is now lower than readState.Index' (duration: 1.082691ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:43.399749Z","caller":"traceutil/trace.go:171","msg":"trace[1754802993] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"398.360084ms","start":"2026-08-11T08:16:43.00135Z","end":"2026-08-11T08:16:43.39971Z","steps":["trace[1754802993] 'process raft request' (duration: 397.535518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:43.399915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.68897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:16:43.399926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:43.00132Z","time spent":"398.513687ms","remote":"127.0.0.1:48970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:43.39996Z","caller":"traceutil/trace.go:171","msg":"trace[1793373015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"323.811342ms","start":"2026-08-11T08:16:43.076134Z","end":"2026-08-11T08:16:43.399946Z","steps":["trace[1793373015] 'agreement among raft nodes before linearized reading' (duration: 323.716639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:43.400006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:43.076105Z","time spent":"323.890673ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:16:43.512937Z","caller":"traceutil/trace.go:171","msg":"trace[215670062] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"100.236881ms","start":"2026-08-11T08:16:43.412675Z","end":"2026-08-11T08:16:43.512912Z","steps":["trace[215670062] 'read index received' (duration: 54.5617ms)","trace[215670062] 'applied index is now lower than readState.Index' (duration: 45.673701ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:43.513057Z","caller":"traceutil/trace.go:171","msg":"trace[826928296] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"107.800564ms","start":"2026-08-11T08:16:43.405242Z","end":"2026-08-11T08:16:43.513043Z","steps":["trace[826928296] 'process raft request' (duration: 62.090013ms)","trace[826928296] 'compare' (duration: 45.438196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:16:43.513588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.853284ms","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-08-11T08:16:43.514129Z","caller":"traceutil/trace.go:171","msg":"trace[94585231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:698; }","duration":"101.451526ms","start":"2026-08-11T08:16:43.412645Z","end":"2026-08-11T08:16:43.514097Z","steps":["trace[94585231] 'agreement among raft nodes before linearized reading' (duration: 100.532537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:43.536012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.422928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T08:16:43.536073Z","caller":"traceutil/trace.go:171","msg":"trace[1218779238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"108.54798ms","start":"2026-08-11T08:16:43.42751Z","end":"2026-08-11T08:16:43.536058Z","steps":["trace[1218779238] 'agreement among raft nodes before linearized reading' (duration: 108.325196ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:44.357743Z","caller":"traceutil/trace.go:171","msg":"trace[1408381461] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"281.386915ms","start":"2026-08-11T08:16:44.076332Z","end":"2026-08-11T08:16:44.357719Z","steps":["trace[1408381461] 'read index received' (duration: 279.482796ms)","trace[1408381461] 'applied index is now lower than readState.Index' (duration: 1.902979ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:44.357816Z","caller":"traceutil/trace.go:171","msg":"trace[1931357615] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"318.660608ms","start":"2026-08-11T08:16:44.039123Z","end":"2026-08-11T08:16:44.357784Z","steps":["trace[1931357615] 'process raft request' (duration: 317.158387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:44.357869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.522408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:16:44.358589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.039092Z","time spent":"319.402021ms","remote":"127.0.0.1:48970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:44.358859Z","caller":"traceutil/trace.go:171","msg":"trace[1000711411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"281.62788ms","start":"2026-08-11T08:16:44.076266Z","end":"2026-08-11T08:16:44.357894Z","steps":["trace[1000711411] 'agreement among raft nodes before linearized reading' (duration: 281.551268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:44.813414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.392145Z","time spent":"421.254587ms","remote":"127.0.0.1:39394","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T08:16:44.813979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.924205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:16:44.81415Z","caller":"traceutil/trace.go:171","msg":"trace[284606001] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"314.383462ms","start":"2026-08-11T08:16:44.499746Z","end":"2026-08-11T08:16:44.814129Z","steps":["trace[284606001] 'read index received' (duration: 152.118889ms)","trace[284606001] 'applied index is now lower than readState.Index' (duration: 162.262713ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:44.81437Z","caller":"traceutil/trace.go:171","msg":"trace[1992542070] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"396.594159ms","start":"2026-08-11T08:16:44.417726Z","end":"2026-08-11T08:16:44.81432Z","steps":["trace[1992542070] 'process raft request' (duration: 234.204124ms)","trace[1992542070] 'compare' (duration: 161.619939ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:16:44.814547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.417704Z","time spent":"396.756653ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T08:16:44.815114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.3366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:16:44.815227Z","caller":"traceutil/trace.go:171","msg":"trace[934568631] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:704; }","duration":"315.506724ms","start":"2026-08-11T08:16:44.499698Z","end":"2026-08-11T08:16:44.815205Z","steps":["trace[934568631] 'agreement among raft nodes before linearized reading' (duration: 315.30648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:44.815279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.499677Z","time spent":"315.586115ms","remote":"127.0.0.1:49088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:16:45.325085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108591175,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T08:16:46.354498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.142678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:16:46.35473Z","caller":"traceutil/trace.go:171","msg":"trace[1889739029] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"1.530055549s","start":"2026-08-11T08:16:44.824651Z","end":"2026-08-11T08:16:46.354707Z","steps":["trace[1889739029] 'read index received' (duration: 970.549274ms)","trace[1889739029] 'applied index is now lower than readState.Index' (duration: 559.505035ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:46.354829Z","caller":"traceutil/trace.go:171","msg":"trace[1610587448] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.507382653s","start":"2026-08-11T08:16:44.84741Z","end":"2026-08-11T08:16:46.354793Z","steps":["trace[1610587448] 'process raft request' (duration: 1.50723642s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.354924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278231841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:16:46.354987Z","caller":"traceutil/trace.go:171","msg":"trace[38092262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"1.278319062s","start":"2026-08-11T08:16:45.076659Z","end":"2026-08-11T08:16:46.354978Z","steps":["trace[38092262] 'agreement among raft nodes before linearized reading' (duration: 1.27824786s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.355025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:45.076643Z","time spent":"1.278375064s","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T08:16:46.355052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.20731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T08:16:46.355063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.84739Z","time spent":"1.507570506s","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:16:46.355107Z","caller":"traceutil/trace.go:171","msg":"trace[1618940233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"743.296781ms","start":"2026-08-11T08:16:45.611798Z","end":"2026-08-11T08:16:46.355095Z","steps":["trace[1618940233] 'agreement among raft nodes before linearized reading' (duration: 743.134018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.355144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:45.61178Z","time spent":"743.357693ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T08:16:46.354881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530224503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-11T08:16:46.354886Z","caller":"traceutil/trace.go:171","msg":"trace[1772113020] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.533129751s","start":"2026-08-11T08:16:44.821728Z","end":"2026-08-11T08:16:46.354858Z","steps":["trace[1772113020] 'process raft request' (duration: 973.488104ms)","trace[1772113020] 'compare' (duration: 558.931834ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:46.356403Z","caller":"traceutil/trace.go:171","msg":"trace[286571038] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:706; }","duration":"1.531732864s","start":"2026-08-11T08:16:44.824628Z","end":"2026-08-11T08:16:46.35636Z","steps":["trace[286571038] 'agreement among raft nodes before linearized reading' (duration: 1.530202553s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.356557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.821711Z","time spent":"1.534770165s","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:16:46.355296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.969011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T08:16:46.356723Z","caller":"traceutil/trace.go:171","msg":"trace[1430426590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"839.394379ms","start":"2026-08-11T08:16:45.517299Z","end":"2026-08-11T08:16:46.356693Z","steps":["trace[1430426590] 'agreement among raft nodes before linearized reading' (duration: 837.93777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.355331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.703711ms","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-08-11T08:16:46.357986Z","caller":"traceutil/trace.go:171","msg":"trace[393063408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:706; }","duration":"762.382446ms","start":"2026-08-11T08:16:45.59558Z","end":"2026-08-11T08:16:46.357962Z","steps":["trace[393063408] 'agreement among raft nodes before linearized reading' (duration: 759.643651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:46.358061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:45.595559Z","time spent":"762.483397ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T08:16:46.358019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:45.517281Z","time spent":"840.676695ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T08:16:46.358124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:16:44.824621Z","time spent":"1.531878356s","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-08-11T08:16:48.037373Z","caller":"traceutil/trace.go:171","msg":"trace[381117518] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"207.196949ms","start":"2026-08-11T08:16:47.830151Z","end":"2026-08-11T08:16:48.037348Z","steps":["trace[381117518] 'process raft request' (duration: 207.038816ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:50.552329Z","caller":"traceutil/trace.go:171","msg":"trace[305496915] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"125.91266ms","start":"2026-08-11T08:16:50.426386Z","end":"2026-08-11T08:16:50.552299Z","steps":["trace[305496915] 'read index received' (duration: 110.618581ms)","trace[305496915] 'applied index is now lower than readState.Index' (duration: 15.292809ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:50.552518Z","caller":"traceutil/trace.go:171","msg":"trace[1325748850] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"127.159855ms","start":"2026-08-11T08:16:50.425324Z","end":"2026-08-11T08:16:50.552484Z","steps":["trace[1325748850] 'process raft request' (duration: 111.641292ms)","trace[1325748850] 'compare' (duration: 15.084574ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:16:50.552642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.234456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-08-11T08:16:50.5527Z","caller":"traceutil/trace.go:171","msg":"trace[258612160] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:775; }","duration":"126.338287ms","start":"2026-08-11T08:16:50.426345Z","end":"2026-08-11T08:16:50.552683Z","steps":["trace[258612160] 'agreement among raft nodes before linearized reading' (duration: 126.062402ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:51.061484Z","caller":"traceutil/trace.go:171","msg":"trace[618230805] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"229.407827ms","start":"2026-08-11T08:16:50.832043Z","end":"2026-08-11T08:16:51.061451Z","steps":["trace[618230805] 'process raft request' (duration: 229.190492ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:52.85448Z","caller":"traceutil/trace.go:171","msg":"trace[616628163] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"135.962962ms","start":"2026-08-11T08:16:52.71843Z","end":"2026-08-11T08:16:52.854393Z","steps":["trace[616628163] 'process raft request' (duration: 135.703997ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:16:52.947482Z","caller":"traceutil/trace.go:171","msg":"trace[906887207] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"116.51811ms","start":"2026-08-11T08:16:52.830943Z","end":"2026-08-11T08:16:52.947461Z","steps":["trace[906887207] 'process raft request' (duration: 83.977234ms)","trace[906887207] 'compare' (duration: 32.201469ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:54.35463Z","caller":"traceutil/trace.go:171","msg":"trace[457921030] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"172.189583ms","start":"2026-08-11T08:16:54.182423Z","end":"2026-08-11T08:16:54.354612Z","steps":["trace[457921030] 'read index received' (duration: 172.032528ms)","trace[457921030] 'applied index is now lower than readState.Index' (duration: 153.974µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:16:54.354685Z","caller":"traceutil/trace.go:171","msg":"trace[1037540053] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"175.146752ms","start":"2026-08-11T08:16:54.179504Z","end":"2026-08-11T08:16:54.354651Z","steps":["trace[1037540053] 'process raft request' (duration: 174.997238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:16:54.354787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.347316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:16:54.354828Z","caller":"traceutil/trace.go:171","msg":"trace[634450914] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:808; }","duration":"172.443677ms","start":"2026-08-11T08:16:54.182371Z","end":"2026-08-11T08:16:54.354815Z","steps":["trace[634450914] 'agreement among raft nodes before linearized reading' (duration: 172.333235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:02.906207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.989365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T08:17:02.906271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.575932ms","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-08-11T08:17:02.90628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.10048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T08:17:02.90632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.958032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T08:17:02.906363Z","caller":"traceutil/trace.go:171","msg":"trace[516745182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"419.721445ms","start":"2026-08-11T08:17:02.486618Z","end":"2026-08-11T08:17:02.90634Z","steps":["trace[516745182] 'range keys from in-memory index tree' (duration: 419.391218ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:02.906403Z","caller":"traceutil/trace.go:171","msg":"trace[864847994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"255.084135ms","start":"2026-08-11T08:17:02.651298Z","end":"2026-08-11T08:17:02.906382Z","steps":["trace[864847994] 'range keys from in-memory index tree' (duration: 254.669256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:02.906412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:02.486598Z","time spent":"419.805316ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T08:17:02.906368Z","caller":"traceutil/trace.go:171","msg":"trace[1788039904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"408.229993ms","start":"2026-08-11T08:17:02.49812Z","end":"2026-08-11T08:17:02.90635Z","steps":["trace[1788039904] 'range keys from in-memory index tree' (duration: 407.845046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:02.906773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:02.498102Z","time spent":"408.646732ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T08:17:02.906303Z","caller":"traceutil/trace.go:171","msg":"trace[1273093764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"444.117698ms","start":"2026-08-11T08:17:02.46216Z","end":"2026-08-11T08:17:02.906278Z","steps":["trace[1273093764] 'range keys from in-memory index tree' (duration: 443.779591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:02.907101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:02.462144Z","time spent":"444.894444ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T08:17:03.138181Z","caller":"traceutil/trace.go:171","msg":"trace[1759173043] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"441.017065ms","start":"2026-08-11T08:17:02.697142Z","end":"2026-08-11T08:17:03.138159Z","steps":["trace[1759173043] 'process raft request' (duration: 440.839081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:03.138319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:02.697121Z","time spent":"441.129228ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:03.141968Z","caller":"traceutil/trace.go:171","msg":"trace[657605508] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"231.186483ms","start":"2026-08-11T08:17:02.910759Z","end":"2026-08-11T08:17:03.141946Z","steps":["trace[657605508] 'process raft request' (duration: 231.04273ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:03.142397Z","caller":"traceutil/trace.go:171","msg":"trace[48415430] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"231.53698ms","start":"2026-08-11T08:17:02.910843Z","end":"2026-08-11T08:17:03.14238Z","steps":["trace[48415430] 'process raft request' (duration: 231.380257ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:03.142417Z","caller":"traceutil/trace.go:171","msg":"trace[1914102579] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"230.963248ms","start":"2026-08-11T08:17:02.911426Z","end":"2026-08-11T08:17:03.142389Z","steps":["trace[1914102579] 'process raft request' (duration: 230.884517ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:03.142828Z","caller":"traceutil/trace.go:171","msg":"trace[1114792708] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"232.05559ms","start":"2026-08-11T08:17:02.910762Z","end":"2026-08-11T08:17:03.142817Z","steps":["trace[1114792708] 'process raft request' (duration: 231.136872ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:06.965648Z","caller":"traceutil/trace.go:171","msg":"trace[2104163310] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"101.840875ms","start":"2026-08-11T08:17:06.863732Z","end":"2026-08-11T08:17:06.965573Z","steps":["trace[2104163310] 'read index received' (duration: 62.4934ms)","trace[2104163310] 'applied index is now lower than readState.Index' (duration: 39.346525ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:06.965696Z","caller":"traceutil/trace.go:171","msg":"trace[1071499787] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"102.064959ms","start":"2026-08-11T08:17:06.863614Z","end":"2026-08-11T08:17:06.965679Z","steps":["trace[1071499787] 'process raft request' (duration: 101.358554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:06.965874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.11835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-08-11T08:17:06.965957Z","caller":"traceutil/trace.go:171","msg":"trace[1822632733] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:910; }","duration":"102.247102ms","start":"2026-08-11T08:17:06.863697Z","end":"2026-08-11T08:17:06.965944Z","steps":["trace[1822632733] 'agreement among raft nodes before linearized reading' (duration: 102.023297ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:11.045908Z","caller":"traceutil/trace.go:171","msg":"trace[84477713] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"137.639647ms","start":"2026-08-11T08:17:10.908245Z","end":"2026-08-11T08:17:11.045885Z","steps":["trace[84477713] 'process raft request' (duration: 137.042065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:18.58Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108591777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T08:17:18.694508Z","caller":"traceutil/trace.go:171","msg":"trace[194473395] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"771.596393ms","start":"2026-08-11T08:17:17.922888Z","end":"2026-08-11T08:17:18.694484Z","steps":["trace[194473395] 'process raft request' (duration: 771.46852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:18.694652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:17.922868Z","time spent":"771.708645ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:18.995704Z","caller":"traceutil/trace.go:171","msg":"trace[1935752453] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"916.064947ms","start":"2026-08-11T08:17:18.079613Z","end":"2026-08-11T08:17:18.995678Z","steps":["trace[1935752453] 'read index received' (duration: 614.764109ms)","trace[1935752453] 'applied index is now lower than readState.Index' (duration: 301.299838ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:18.995732Z","caller":"traceutil/trace.go:171","msg":"trace[24227793] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"810.247142ms","start":"2026-08-11T08:17:18.185455Z","end":"2026-08-11T08:17:18.995702Z","steps":["trace[24227793] 'process raft request' (duration: 769.95912ms)","trace[24227793] 'compare' (duration: 39.629989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:17:18.995836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.207079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:17:18.995869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.245936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:17:18.995877Z","caller":"traceutil/trace.go:171","msg":"trace[1878084899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"916.295552ms","start":"2026-08-11T08:17:18.079568Z","end":"2026-08-11T08:17:18.995864Z","steps":["trace[1878084899] 'agreement among raft nodes before linearized reading' (duration: 916.211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:18.995871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:18.185433Z","time spent":"810.355134ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:17:18.995881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.967374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:17:18.995906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:18.07955Z","time spent":"916.350302ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:17:18.995919Z","caller":"traceutil/trace.go:171","msg":"trace[999482879] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1017; }","duration":"439.030575ms","start":"2026-08-11T08:17:18.556875Z","end":"2026-08-11T08:17:18.995906Z","steps":["trace[999482879] 'agreement among raft nodes before linearized reading' (duration: 438.972014ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:18.995916Z","caller":"traceutil/trace.go:171","msg":"trace[644925804] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1017; }","duration":"480.332687ms","start":"2026-08-11T08:17:18.515573Z","end":"2026-08-11T08:17:18.995906Z","steps":["trace[644925804] 'agreement among raft nodes before linearized reading' (duration: 480.250576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:18.995951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:18.556861Z","time spent":"439.079506ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:17:18.995956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:18.515558Z","time spent":"480.389129ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-08-11T08:17:19.666971Z","caller":"traceutil/trace.go:171","msg":"trace[112428187] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"363.641304ms","start":"2026-08-11T08:17:19.303303Z","end":"2026-08-11T08:17:19.666944Z","steps":["trace[112428187] 'read index received' (duration: 363.451851ms)","trace[112428187] 'applied index is now lower than readState.Index' (duration: 188.403µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:19.667058Z","caller":"traceutil/trace.go:171","msg":"trace[384412920] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"387.905784ms","start":"2026-08-11T08:17:19.279121Z","end":"2026-08-11T08:17:19.667026Z","steps":["trace[384412920] 'process raft request' (duration: 387.667229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:19.667169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.056569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T08:17:19.667194Z","caller":"traceutil/trace.go:171","msg":"trace[379354412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"344.11517ms","start":"2026-08-11T08:17:19.323071Z","end":"2026-08-11T08:17:19.667186Z","steps":["trace[379354412] 'agreement among raft nodes before linearized reading' (duration: 344.005598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:19.667229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.32306Z","time spent":"344.161052ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T08:17:19.667223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.279092Z","time spent":"388.034696ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:17:19.667288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.979097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T08:17:19.667294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.977761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T08:17:19.667326Z","caller":"traceutil/trace.go:171","msg":"trace[1993618996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"344.063479ms","start":"2026-08-11T08:17:19.323252Z","end":"2026-08-11T08:17:19.667315Z","steps":["trace[1993618996] 'agreement among raft nodes before linearized reading' (duration: 343.911436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:19.667359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.323245Z","time spent":"344.106429ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T08:17:19.667367Z","caller":"traceutil/trace.go:171","msg":"trace[1810279586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"364.075433ms","start":"2026-08-11T08:17:19.303272Z","end":"2026-08-11T08:17:19.667347Z","steps":["trace[1810279586] 'agreement among raft nodes before linearized reading' (duration: 363.801548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:19.667458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.303257Z","time spent":"364.188295ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T08:17:20.383239Z","caller":"traceutil/trace.go:171","msg":"trace[152644166] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"712.772496ms","start":"2026-08-11T08:17:19.670439Z","end":"2026-08-11T08:17:20.383212Z","steps":["trace[152644166] 'process raft request' (duration: 712.603453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:20.383464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.670397Z","time spent":"712.917689ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:20.392536Z","caller":"traceutil/trace.go:171","msg":"trace[1458631706] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1057; }","duration":"318.35665ms","start":"2026-08-11T08:17:20.074159Z","end":"2026-08-11T08:17:20.392516Z","steps":["trace[1458631706] 'read index received' (duration: 308.921161ms)","trace[1458631706] 'applied index is now lower than readState.Index' (duration: 9.434469ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:20.392599Z","caller":"traceutil/trace.go:171","msg":"trace[1679778816] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"721.128095ms","start":"2026-08-11T08:17:19.671453Z","end":"2026-08-11T08:17:20.392581Z","steps":["trace[1679778816] 'process raft request' (duration: 720.90973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:20.392681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.671442Z","time spent":"721.195786ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:17:20.392688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.518694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:17:20.392702Z","caller":"traceutil/trace.go:171","msg":"trace[1836662701] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"720.174116ms","start":"2026-08-11T08:17:19.672506Z","end":"2026-08-11T08:17:20.39268Z","steps":["trace[1836662701] 'process raft request' (duration: 719.962252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:20.392822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:19.67249Z","time spent":"720.264987ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:20.392724Z","caller":"traceutil/trace.go:171","msg":"trace[249571877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"318.607015ms","start":"2026-08-11T08:17:20.074106Z","end":"2026-08-11T08:17:20.392713Z","steps":["trace[249571877] 'agreement among raft nodes before linearized reading' (duration: 318.493253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:20.392887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:20.074078Z","time spent":"318.79733ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:17:20.860544Z","caller":"traceutil/trace.go:171","msg":"trace[1854423809] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"156.884745ms","start":"2026-08-11T08:17:20.703616Z","end":"2026-08-11T08:17:20.860501Z","steps":["trace[1854423809] 'process raft request' (duration: 156.69005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:47.319173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.811348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:17:47.319245Z","caller":"traceutil/trace.go:171","msg":"trace[630086828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"244.951501ms","start":"2026-08-11T08:17:47.074279Z","end":"2026-08-11T08:17:47.31923Z","steps":["trace[630086828] 'range keys from in-memory index tree' (duration: 244.683785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:47.319177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.640197ms","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-08-11T08:17:47.319623Z","caller":"traceutil/trace.go:171","msg":"trace[344840000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1116; }","duration":"257.862821ms","start":"2026-08-11T08:17:47.061453Z","end":"2026-08-11T08:17:47.319315Z","steps":["trace[344840000] 'range keys from in-memory index tree' (duration: 257.502634ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:47.396877Z","caller":"traceutil/trace.go:171","msg":"trace[1543091503] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"224.925536ms","start":"2026-08-11T08:17:47.171927Z","end":"2026-08-11T08:17:47.396852Z","steps":["trace[1543091503] 'process raft request' (duration: 224.711672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:48.456563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.183608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:17:48.456647Z","caller":"traceutil/trace.go:171","msg":"trace[1122947970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"381.31199ms","start":"2026-08-11T08:17:48.075317Z","end":"2026-08-11T08:17:48.456629Z","steps":["trace[1122947970] 'range keys from in-memory index tree' (duration: 381.085386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:48.456694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:48.0753Z","time spent":"381.382452ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:17:48.893358Z","caller":"traceutil/trace.go:171","msg":"trace[1357554468] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"285.924666ms","start":"2026-08-11T08:17:48.607414Z","end":"2026-08-11T08:17:48.893339Z","steps":["trace[1357554468] 'read index received' (duration: 285.824144ms)","trace[1357554468] 'applied index is now lower than readState.Index' (duration: 99.572µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:17:48.893532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.100509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T08:17:48.893504Z","caller":"traceutil/trace.go:171","msg":"trace[1631728352] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"289.720784ms","start":"2026-08-11T08:17:48.603749Z","end":"2026-08-11T08:17:48.89347Z","steps":["trace[1631728352] 'process raft request' (duration: 289.471779ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:48.893565Z","caller":"traceutil/trace.go:171","msg":"trace[2018136100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"286.166821ms","start":"2026-08-11T08:17:48.607391Z","end":"2026-08-11T08:17:48.893557Z","steps":["trace[2018136100] 'agreement among raft nodes before linearized reading' (duration: 286.043729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:48.893774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.584315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:17:48.893803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.345045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T08:17:48.893846Z","caller":"traceutil/trace.go:171","msg":"trace[469589974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1121; }","duration":"193.720027ms","start":"2026-08-11T08:17:48.700111Z","end":"2026-08-11T08:17:48.893831Z","steps":["trace[469589974] 'agreement among raft nodes before linearized reading' (duration: 193.564894ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:48.893871Z","caller":"traceutil/trace.go:171","msg":"trace[678686657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"286.438046ms","start":"2026-08-11T08:17:48.607416Z","end":"2026-08-11T08:17:48.893854Z","steps":["trace[678686657] 'agreement among raft nodes before linearized reading' (duration: 286.230492ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:49.108623Z","caller":"traceutil/trace.go:171","msg":"trace[695075290] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"211.824192ms","start":"2026-08-11T08:17:48.89677Z","end":"2026-08-11T08:17:49.108594Z","steps":["trace[695075290] 'process raft request' (duration: 143.042135ms)","trace[695075290] 'compare' (duration: 68.43609ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:49.108794Z","caller":"traceutil/trace.go:171","msg":"trace[500280466] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"209.340993ms","start":"2026-08-11T08:17:48.899444Z","end":"2026-08-11T08:17:49.108785Z","steps":["trace[500280466] 'process raft request' (duration: 209.053747ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:50.132727Z","caller":"traceutil/trace.go:171","msg":"trace[315562722] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"730.098105ms","start":"2026-08-11T08:17:49.402601Z","end":"2026-08-11T08:17:50.132699Z","steps":["trace[315562722] 'process raft request' (duration: 729.939552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:50.132884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:49.402569Z","time spent":"730.234648ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:50.206223Z","caller":"traceutil/trace.go:171","msg":"trace[804948715] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1169; }","duration":"362.42211ms","start":"2026-08-11T08:17:49.843776Z","end":"2026-08-11T08:17:50.206198Z","steps":["trace[804948715] 'read index received' (duration: 288.767104ms)","trace[804948715] 'applied index is now lower than readState.Index' (duration: 73.640945ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:50.206367Z","caller":"traceutil/trace.go:171","msg":"trace[82670322] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"801.032536ms","start":"2026-08-11T08:17:49.405291Z","end":"2026-08-11T08:17:50.206324Z","steps":["trace[82670322] 'process raft request' (duration: 797.241709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:50.206488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:49.405271Z","time spent":"801.162048ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:17:50.206483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.546623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:17:50.206521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.729506ms","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-08-11T08:17:50.206536Z","caller":"traceutil/trace.go:171","msg":"trace[71728253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"130.632365ms","start":"2026-08-11T08:17:50.075889Z","end":"2026-08-11T08:17:50.206522Z","steps":["trace[71728253] 'agreement among raft nodes before linearized reading' (duration: 130.540023ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:50.20659Z","caller":"traceutil/trace.go:171","msg":"trace[261537448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"362.822327ms","start":"2026-08-11T08:17:49.843728Z","end":"2026-08-11T08:17:50.20655Z","steps":["trace[261537448] 'agreement among raft nodes before linearized reading' (duration: 362.618723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:50.206644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:49.843708Z","time spent":"362.91678ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T08:17:53.095302Z","caller":"traceutil/trace.go:171","msg":"trace[1284796081] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"115.03123ms","start":"2026-08-11T08:17:52.980235Z","end":"2026-08-11T08:17:53.095266Z","steps":["trace[1284796081] 'process raft request' (duration: 21.960463ms)","trace[1284796081] 'compare' (duration: 92.909944ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:53.566911Z","caller":"traceutil/trace.go:171","msg":"trace[666930280] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"441.139817ms","start":"2026-08-11T08:17:53.125738Z","end":"2026-08-11T08:17:53.566878Z","steps":["trace[666930280] 'process raft request' (duration: 440.911822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:53.567137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:53.125714Z","time spent":"441.29957ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:53.714665Z","caller":"traceutil/trace.go:171","msg":"trace[223770367] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"588.331647ms","start":"2026-08-11T08:17:53.126305Z","end":"2026-08-11T08:17:53.714637Z","steps":["trace[223770367] 'process raft request' (duration: 521.845855ms)","trace[223770367] 'compare' (duration: 66.173935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:17:53.714791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:53.126286Z","time spent":"588.44435ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:17:54.405818Z","caller":"traceutil/trace.go:171","msg":"trace[1014225282] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"117.717344ms","start":"2026-08-11T08:17:54.288073Z","end":"2026-08-11T08:17:54.405791Z","steps":["trace[1014225282] 'process raft request' (duration: 99.283902ms)","trace[1014225282] 'compare' (duration: 18.303519ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:10.452216Z","caller":"traceutil/trace.go:171","msg":"trace[1212901095] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"107.813694ms","start":"2026-08-11T08:18:10.344378Z","end":"2026-08-11T08:18:10.452192Z","steps":["trace[1212901095] 'process raft request' (duration: 107.242752ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:11.441409Z","caller":"traceutil/trace.go:171","msg":"trace[1104823776] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"364.886829ms","start":"2026-08-11T08:18:11.076492Z","end":"2026-08-11T08:18:11.441379Z","steps":["trace[1104823776] 'read index received' (duration: 364.692865ms)","trace[1104823776] 'applied index is now lower than readState.Index' (duration: 192.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:11.441791Z","caller":"traceutil/trace.go:171","msg":"trace[24661229] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"414.588223ms","start":"2026-08-11T08:18:11.027186Z","end":"2026-08-11T08:18:11.441774Z","steps":["trace[24661229] 'process raft request' (duration: 414.018671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:11.441946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.027165Z","time spent":"414.668845ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:11.442196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.702596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:11.442237Z","caller":"traceutil/trace.go:171","msg":"trace[698902891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"365.778527ms","start":"2026-08-11T08:18:11.076442Z","end":"2026-08-11T08:18:11.442221Z","steps":["trace[698902891] 'agreement among raft nodes before linearized reading' (duration: 365.716226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:11.44227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.076419Z","time spent":"365.845958ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T08:18:12.235344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.019582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.127\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T08:18:12.235524Z","caller":"traceutil/trace.go:171","msg":"trace[606586986] range","detail":"{range_begin:/registry/masterleases/199.19.213.127; range_end:; response_count:1; response_revision:1245; }","duration":"558.254476ms","start":"2026-08-11T08:18:11.677241Z","end":"2026-08-11T08:18:12.235496Z","steps":["trace[606586986] 'range keys from in-memory index tree' (duration: 557.799418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:12.235582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.677226Z","time spent":"558.343848ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.127\" "} {"level":"warn","ts":"2026-08-11T08:18:12.235591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.898576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:18:12.235603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.325486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T08:18:12.235674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.540744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-11T08:18:12.235561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.99024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:12.23574Z","caller":"traceutil/trace.go:171","msg":"trace[1993109394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"561.600735ms","start":"2026-08-11T08:18:11.674119Z","end":"2026-08-11T08:18:12.23572Z","steps":["trace[1993109394] 'range keys from in-memory index tree' (duration: 561.353299ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:12.235736Z","caller":"traceutil/trace.go:171","msg":"trace[1230968382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"309.553952ms","start":"2026-08-11T08:18:11.926151Z","end":"2026-08-11T08:18:12.235705Z","steps":["trace[1230968382] 'range keys from in-memory index tree' (duration: 309.00142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:12.235785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.674065Z","time spent":"561.713258ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T08:18:12.235809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.926122Z","time spent":"309.675105ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T08:18:12.235808Z","caller":"traceutil/trace.go:171","msg":"trace[1167846005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"792.299058ms","start":"2026-08-11T08:18:11.443485Z","end":"2026-08-11T08:18:12.235784Z","steps":["trace[1167846005] 'range keys from in-memory index tree' (duration: 791.928009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:12.2359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.443465Z","time spent":"792.416981ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:18:12.235709Z","caller":"traceutil/trace.go:171","msg":"trace[756311736] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1245; }","duration":"599.127302ms","start":"2026-08-11T08:18:11.636553Z","end":"2026-08-11T08:18:12.235681Z","steps":["trace[756311736] 'count revisions from in-memory index tree' (duration: 598.782724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:12.236206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:11.636522Z","time spent":"599.481968ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:16.877529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.646541ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-11T08:18:29.574858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108592505,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T08:18:29.8955Z","caller":"traceutil/trace.go:171","msg":"trace[272856036] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"821.04977ms","start":"2026-08-11T08:18:29.074427Z","end":"2026-08-11T08:18:29.895477Z","steps":["trace[272856036] 'read index received' (duration: 820.841726ms)","trace[272856036] 'applied index is now lower than readState.Index' (duration: 207.114µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:29.895624Z","caller":"traceutil/trace.go:171","msg":"trace[1817922173] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"867.386882ms","start":"2026-08-11T08:18:29.028227Z","end":"2026-08-11T08:18:29.895613Z","steps":["trace[1817922173] 'process raft request' (duration: 867.073795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:29.895722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.02821Z","time spent":"867.439163ms","remote":"127.0.0.1:49052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:29.895951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.186222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-11T08:18:29.895969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.525212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:29.896019Z","caller":"traceutil/trace.go:171","msg":"trace[1063919692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"339.301075ms","start":"2026-08-11T08:18:29.556702Z","end":"2026-08-11T08:18:29.896004Z","steps":["trace[1063919692] 'agreement among raft nodes before linearized reading' (duration: 339.137222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:29.896028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.899096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:18:29.896047Z","caller":"traceutil/trace.go:171","msg":"trace[571731890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"821.646333ms","start":"2026-08-11T08:18:29.074376Z","end":"2026-08-11T08:18:29.896023Z","steps":["trace[571731890] 'agreement among raft nodes before linearized reading' (duration: 821.49402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:29.896058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.556682Z","time spent":"339.366817ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T08:18:29.896066Z","caller":"traceutil/trace.go:171","msg":"trace[1858137505] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1321; }","duration":"300.975628ms","start":"2026-08-11T08:18:29.595079Z","end":"2026-08-11T08:18:29.896055Z","steps":["trace[1858137505] 'agreement among raft nodes before linearized reading' (duration: 300.901736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:29.896086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.074355Z","time spent":"821.724155ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T08:18:29.896098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.595062Z","time spent":"301.027949ms","remote":"127.0.0.1:48986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:30.419593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108592508,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T08:18:30.920273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108592508,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T08:18:31.342548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.442091124s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T08:18:31.343019Z","caller":"traceutil/trace.go:171","msg":"trace[813107121] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.442692036s","start":"2026-08-11T08:18:29.900302Z","end":"2026-08-11T08:18:31.342994Z","steps":["trace[813107121] 'process raft request' (duration: 1.442532713s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.3432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.900279Z","time spent":"1.442824058s","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:18:31.387064Z","caller":"traceutil/trace.go:171","msg":"trace[1998929171] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1377; }","duration":"1.467768685s","start":"2026-08-11T08:18:29.919269Z","end":"2026-08-11T08:18:31.387037Z","steps":["trace[1998929171] 'read index received' (duration: 1.423484456s)","trace[1998929171] 'applied index is now lower than readState.Index' (duration: 44.283239ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:31.387076Z","caller":"traceutil/trace.go:171","msg":"trace[1471754245] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.486653208s","start":"2026-08-11T08:18:29.900377Z","end":"2026-08-11T08:18:31.38703Z","steps":["trace[1471754245] 'process raft request' (duration: 1.486203138s)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:31.387189Z","caller":"traceutil/trace.go:171","msg":"trace[970955270] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.480496244s","start":"2026-08-11T08:18:29.906681Z","end":"2026-08-11T08:18:31.387177Z","steps":["trace[970955270] 'process raft request' (duration: 1.480225799s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.387365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.906674Z","time spent":"1.480544175s","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:31.387412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.900353Z","time spent":"1.486888813s","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:31.387487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468214525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:18:31.387541Z","caller":"traceutil/trace.go:171","msg":"trace[1139761675] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1326; }","duration":"1.468311986s","start":"2026-08-11T08:18:29.919217Z","end":"2026-08-11T08:18:31.387529Z","steps":["trace[1139761675] 'agreement among raft nodes before linearized reading' (duration: 1.468225994s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.387575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.919198Z","time spent":"1.468366818s","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:31.387619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382868212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-08-11T08:18:31.387715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.365593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:31.387665Z","caller":"traceutil/trace.go:171","msg":"trace[528203648] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"734.992615ms","start":"2026-08-11T08:18:30.652659Z","end":"2026-08-11T08:18:31.387651Z","steps":["trace[528203648] 'process raft request' (duration: 734.332752ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:31.387753Z","caller":"traceutil/trace.go:171","msg":"trace[1381884432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1326; }","duration":"771.426874ms","start":"2026-08-11T08:18:30.616317Z","end":"2026-08-11T08:18:31.387744Z","steps":["trace[1381884432] 'agreement among raft nodes before linearized reading' (duration: 771.371573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.387782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.616301Z","time spent":"771.472266ms","remote":"127.0.0.1:44238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:31.387824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.652632Z","time spent":"735.124947ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:18:31.387715Z","caller":"traceutil/trace.go:171","msg":"trace[1057303913] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1326; }","duration":"1.383007275s","start":"2026-08-11T08:18:30.00469Z","end":"2026-08-11T08:18:31.387698Z","steps":["trace[1057303913] 'agreement among raft nodes before linearized reading' (duration: 1.382834942s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.387997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.350008ms","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-08-11T08:18:31.388003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.004671Z","time spent":"1.383313981s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-08-11T08:18:31.388031Z","caller":"traceutil/trace.go:171","msg":"trace[349674528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"585.40409ms","start":"2026-08-11T08:18:30.802618Z","end":"2026-08-11T08:18:31.388022Z","steps":["trace[349674528] 'agreement among raft nodes before linearized reading' (duration: 585.295537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.822455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:18:31.388074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.802601Z","time spent":"585.465301ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T08:18:31.38808Z","caller":"traceutil/trace.go:171","msg":"trace[180587575] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1326; }","duration":"815.892338ms","start":"2026-08-11T08:18:30.572176Z","end":"2026-08-11T08:18:31.388068Z","steps":["trace[180587575] 'agreement among raft nodes before linearized reading' (duration: 815.829615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.412948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-11T08:18:31.388122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.572158Z","time spent":"815.952838ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-08-11T08:18:31.388132Z","caller":"traceutil/trace.go:171","msg":"trace[855818062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"516.510192ms","start":"2026-08-11T08:18:30.871611Z","end":"2026-08-11T08:18:31.388121Z","steps":["trace[855818062] 'agreement among raft nodes before linearized reading' (duration: 516.277506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.871585Z","time spent":"516.570403ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T08:18:31.38834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.540666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T08:18:31.388313Z","caller":"traceutil/trace.go:171","msg":"trace[414829175] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.487898434s","start":"2026-08-11T08:18:29.900376Z","end":"2026-08-11T08:18:31.388275Z","steps":["trace[414829175] 'process raft request' (duration: 1.486453534s)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:31.388371Z","caller":"traceutil/trace.go:171","msg":"trace[2107416648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"907.603228ms","start":"2026-08-11T08:18:30.480759Z","end":"2026-08-11T08:18:31.388363Z","steps":["trace[2107416648] 'agreement among raft nodes before linearized reading' (duration: 907.475496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.44411ms","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":"warn","ts":"2026-08-11T08:18:31.388398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.480745Z","time spent":"907.648049ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T08:18:31.388422Z","caller":"traceutil/trace.go:171","msg":"trace[563358728] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1326; }","duration":"414.538372ms","start":"2026-08-11T08:18:30.973871Z","end":"2026-08-11T08:18:31.388409Z","steps":["trace[563358728] 'agreement among raft nodes before linearized reading' (duration: 414.463581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.973852Z","time spent":"414.597853ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:31.388486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:29.900356Z","time spent":"1.488044267s","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:31.388504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382359562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120445"} {"level":"warn","ts":"2026-08-11T08:18:31.388343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.410584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:18:31.388536Z","caller":"traceutil/trace.go:171","msg":"trace[769788517] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1326; }","duration":"1.382418784s","start":"2026-08-11T08:18:30.006109Z","end":"2026-08-11T08:18:31.388527Z","steps":["trace[769788517] 'agreement among raft nodes before linearized reading' (duration: 1.382309201s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.137193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T08:18:31.38859Z","caller":"traceutil/trace.go:171","msg":"trace[203833714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1326; }","duration":"993.171594ms","start":"2026-08-11T08:18:30.395409Z","end":"2026-08-11T08:18:31.38858Z","steps":["trace[203833714] 'agreement among raft nodes before linearized reading' (duration: 993.080862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312198608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T08:18:31.388611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.39532Z","time spent":"993.286307ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T08:18:31.388625Z","caller":"traceutil/trace.go:171","msg":"trace[354744483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"1.312275309s","start":"2026-08-11T08:18:30.076343Z","end":"2026-08-11T08:18:31.388618Z","steps":["trace[354744483] 'agreement among raft nodes before linearized reading' (duration: 1.312233249s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.076309Z","time spent":"1.312336561s","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T08:18:31.38856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:30.006099Z","time spent":"1.382456295s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":120469,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-08-11T08:18:31.388555Z","caller":"traceutil/trace.go:171","msg":"trace[1922892055] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1326; }","duration":"360.638859ms","start":"2026-08-11T08:18:31.027902Z","end":"2026-08-11T08:18:31.388541Z","steps":["trace[1922892055] 'agreement among raft nodes before linearized reading' (duration: 360.403204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:31.388864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:31.027887Z","time spent":"360.957015ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-11T08:18:32.331156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.110443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:32.33124Z","caller":"traceutil/trace.go:171","msg":"trace[151232704] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1332; }","duration":"104.206705ms","start":"2026-08-11T08:18:32.227013Z","end":"2026-08-11T08:18:32.331219Z","steps":["trace[151232704] 'count revisions from in-memory index tree' (duration: 104.01439ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.560978Z","caller":"traceutil/trace.go:171","msg":"trace[1126578849] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"161.840444ms","start":"2026-08-11T08:18:33.399108Z","end":"2026-08-11T08:18:33.560949Z","steps":["trace[1126578849] 'process raft request' (duration: 161.760602ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.561097Z","caller":"traceutil/trace.go:171","msg":"trace[884756368] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1392; }","duration":"147.80028ms","start":"2026-08-11T08:18:33.413287Z","end":"2026-08-11T08:18:33.561087Z","steps":["trace[884756368] 'read index received' (duration: 62.996568ms)","trace[884756368] 'applied index is now lower than readState.Index' (duration: 84.802452ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:33.561148Z","caller":"traceutil/trace.go:171","msg":"trace[115924485] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"161.866115ms","start":"2026-08-11T08:18:33.399261Z","end":"2026-08-11T08:18:33.561127Z","steps":["trace[115924485] 'process raft request' (duration: 161.64224ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.560988Z","caller":"traceutil/trace.go:171","msg":"trace[2046180673] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"162.14983ms","start":"2026-08-11T08:18:33.398804Z","end":"2026-08-11T08:18:33.560953Z","steps":["trace[2046180673] 'process raft request' (duration: 77.552584ms)","trace[2046180673] 'compare' (duration: 84.344061ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:18:33.561335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.036514ms","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-08-11T08:18:33.561367Z","caller":"traceutil/trace.go:171","msg":"trace[776088001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1338; }","duration":"148.126656ms","start":"2026-08-11T08:18:33.413231Z","end":"2026-08-11T08:18:33.561357Z","steps":["trace[776088001] 'agreement among raft nodes before linearized reading' (duration: 147.939982ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.70318Z","caller":"traceutil/trace.go:171","msg":"trace[818821900] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"136.450409ms","start":"2026-08-11T08:18:33.566711Z","end":"2026-08-11T08:18:33.703162Z","steps":["trace[818821900] 'process raft request' (duration: 109.314258ms)","trace[818821900] 'compare' (duration: 26.60264ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:33.703258Z","caller":"traceutil/trace.go:171","msg":"trace[1066241179] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"136.444398ms","start":"2026-08-11T08:18:33.566791Z","end":"2026-08-11T08:18:33.703235Z","steps":["trace[1066241179] 'process raft request' (duration: 135.964959ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.703311Z","caller":"traceutil/trace.go:171","msg":"trace[1873344549] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"136.416128ms","start":"2026-08-11T08:18:33.566887Z","end":"2026-08-11T08:18:33.703303Z","steps":["trace[1873344549] 'process raft request' (duration: 135.930389ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:33.703252Z","caller":"traceutil/trace.go:171","msg":"trace[437734477] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"136.336527ms","start":"2026-08-11T08:18:33.566878Z","end":"2026-08-11T08:18:33.703214Z","steps":["trace[437734477] 'process raft request' (duration: 135.961519ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:37.634173Z","caller":"traceutil/trace.go:171","msg":"trace[1556332774] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"121.401694ms","start":"2026-08-11T08:18:37.512735Z","end":"2026-08-11T08:18:37.634137Z","steps":["trace[1556332774] 'process raft request' (duration: 121.169328ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:38.850822Z","caller":"traceutil/trace.go:171","msg":"trace[1393459149] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"138.356407ms","start":"2026-08-11T08:18:38.712419Z","end":"2026-08-11T08:18:38.850776Z","steps":["trace[1393459149] 'process raft request' (duration: 138.061891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:39.613237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:18:39.613335Z","caller":"traceutil/trace.go:171","msg":"trace[509777519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"537.269883ms","start":"2026-08-11T08:18:39.076044Z","end":"2026-08-11T08:18:39.613314Z","steps":["trace[509777519] 'range keys from in-memory index tree' (duration: 536.975568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:39.613381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.075995Z","time spent":"537.377935ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T08:18:39.623576Z","caller":"traceutil/trace.go:171","msg":"trace[1069687613] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"196.460176ms","start":"2026-08-11T08:18:39.427082Z","end":"2026-08-11T08:18:39.623542Z","steps":["trace[1069687613] 'process raft request' (duration: 195.997327ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:40.11796Z","caller":"traceutil/trace.go:171","msg":"trace[665433783] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1427; }","duration":"368.09086ms","start":"2026-08-11T08:18:39.749849Z","end":"2026-08-11T08:18:40.11794Z","steps":["trace[665433783] 'read index received' (duration: 337.890617ms)","trace[665433783] 'applied index is now lower than readState.Index' (duration: 30.199103ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:40.117979Z","caller":"traceutil/trace.go:171","msg":"trace[1107417838] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"465.062148ms","start":"2026-08-11T08:18:39.652886Z","end":"2026-08-11T08:18:40.117948Z","steps":["trace[1107417838] 'process raft request' (duration: 434.862665ms)","trace[1107417838] 'compare' (duration: 29.691012ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:40.118061Z","caller":"traceutil/trace.go:171","msg":"trace[473133393] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"463.646619ms","start":"2026-08-11T08:18:39.654405Z","end":"2026-08-11T08:18:40.118052Z","steps":["trace[473133393] 'process raft request' (duration: 463.449074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:40.118126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.654386Z","time spent":"463.694401ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:40.118127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.652865Z","time spent":"465.175171ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T08:18:40.118326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.457027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T08:18:40.118438Z","caller":"traceutil/trace.go:171","msg":"trace[1628142368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"368.63933ms","start":"2026-08-11T08:18:39.749774Z","end":"2026-08-11T08:18:40.118413Z","steps":["trace[1628142368] 'agreement among raft nodes before linearized reading' (duration: 368.328704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:40.118491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.749745Z","time spent":"368.736403ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T08:18:40.118428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.273043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-11T08:18:40.118649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.693061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T08:18:40.11868Z","caller":"traceutil/trace.go:171","msg":"trace[1462948142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"365.779842ms","start":"2026-08-11T08:18:39.752891Z","end":"2026-08-11T08:18:40.11867Z","steps":["trace[1462948142] 'agreement among raft nodes before linearized reading' (duration: 365.699001ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:40.118686Z","caller":"traceutil/trace.go:171","msg":"trace[583550046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"367.555388ms","start":"2026-08-11T08:18:39.75111Z","end":"2026-08-11T08:18:40.118666Z","steps":["trace[583550046] 'agreement among raft nodes before linearized reading' (duration: 367.15677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:18:40.118707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.752858Z","time spent":"365.840834ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T08:18:40.118729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:18:39.7511Z","time spent":"367.616679ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T08:18:43.906611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.265169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-11T08:18:43.906714Z","caller":"traceutil/trace.go:171","msg":"trace[1066207019] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1408; }","duration":"117.445783ms","start":"2026-08-11T08:18:43.789246Z","end":"2026-08-11T08:18:43.906692Z","steps":["trace[1066207019] 'range keys from in-memory index tree' (duration: 116.964083ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:44.344587Z","caller":"traceutil/trace.go:171","msg":"trace[329461860] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"127.360944ms","start":"2026-08-11T08:18:44.217197Z","end":"2026-08-11T08:18:44.344558Z","steps":["trace[329461860] 'process raft request' (duration: 127.213401ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:44.345334Z","caller":"traceutil/trace.go:171","msg":"trace[3756995] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"130.155291ms","start":"2026-08-11T08:18:44.215072Z","end":"2026-08-11T08:18:44.345227Z","steps":["trace[3756995] 'process raft request' (duration: 107.587123ms)","trace[3756995] 'compare' (duration: 21.544617ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:51.860649Z","caller":"traceutil/trace.go:171","msg":"trace[425735749] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"125.074637ms","start":"2026-08-11T08:18:51.735548Z","end":"2026-08-11T08:18:51.860623Z","steps":["trace[425735749] 'read index received' (duration: 82.860211ms)","trace[425735749] 'applied index is now lower than readState.Index' (duration: 42.213416ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:18:51.860789Z","caller":"traceutil/trace.go:171","msg":"trace[1524797050] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"127.377175ms","start":"2026-08-11T08:18:51.733368Z","end":"2026-08-11T08:18:51.860745Z","steps":["trace[1524797050] 'process raft request' (duration: 85.130128ms)","trace[1524797050] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:532; } (duration: 41.986201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:18:51.860929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.346733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-11T08:18:51.860976Z","caller":"traceutil/trace.go:171","msg":"trace[1801386400] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1446; }","duration":"125.478926ms","start":"2026-08-11T08:18:51.735486Z","end":"2026-08-11T08:18:51.860965Z","steps":["trace[1801386400] 'agreement among raft nodes before linearized reading' (duration: 125.256311ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:52.051607Z","caller":"traceutil/trace.go:171","msg":"trace[1432995348] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"121.886664ms","start":"2026-08-11T08:18:51.929653Z","end":"2026-08-11T08:18:52.05154Z","steps":["trace[1432995348] 'process raft request' (duration: 33.03493ms)","trace[1432995348] 'compare' (duration: 88.64675ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:21:30.273169Z","caller":"traceutil/trace.go:171","msg":"trace[392529917] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"250.471432ms","start":"2026-08-11T08:21:30.022673Z","end":"2026-08-11T08:21:30.273144Z","steps":["trace[392529917] 'read index received' (duration: 206.773237ms)","trace[392529917] 'applied index is now lower than readState.Index' (duration: 43.697155ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:21:30.273225Z","caller":"traceutil/trace.go:171","msg":"trace[2034482351] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"365.54662ms","start":"2026-08-11T08:21:29.907645Z","end":"2026-08-11T08:21:30.273192Z","steps":["trace[2034482351] 'process raft request' (duration: 321.892255ms)","trace[2034482351] 'compare' (duration: 43.472132ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:21:30.27337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.685756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"warn","ts":"2026-08-11T08:21:30.27339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:29.907625Z","time spent":"365.690403ms","remote":"127.0.0.1:49230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T08:21:30.273422Z","caller":"traceutil/trace.go:171","msg":"trace[336959079] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2186; }","duration":"250.797859ms","start":"2026-08-11T08:21:30.022613Z","end":"2026-08-11T08:21:30.273411Z","steps":["trace[336959079] 'agreement among raft nodes before linearized reading' (duration: 250.676546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:30.273518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.210425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:21:30.273559Z","caller":"traceutil/trace.go:171","msg":"trace[623595530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"199.286637ms","start":"2026-08-11T08:21:30.07426Z","end":"2026-08-11T08:21:30.273547Z","steps":["trace[623595530] 'agreement among raft nodes before linearized reading' (duration: 199.202085ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:30.397522Z","caller":"traceutil/trace.go:171","msg":"trace[60292224] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"116.703199ms","start":"2026-08-11T08:21:30.280793Z","end":"2026-08-11T08:21:30.397497Z","steps":["trace[60292224] 'process raft request' (duration: 115.844212ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:41.176214Z","caller":"traceutil/trace.go:171","msg":"trace[1443208504] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"100.155017ms","start":"2026-08-11T08:21:41.076036Z","end":"2026-08-11T08:21:41.176191Z","steps":["trace[1443208504] 'read index received' (duration: 99.942313ms)","trace[1443208504] 'applied index is now lower than readState.Index' (duration: 211.304µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:21:41.176362Z","caller":"traceutil/trace.go:171","msg":"trace[544993132] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"131.64231ms","start":"2026-08-11T08:21:41.044706Z","end":"2026-08-11T08:21:41.176349Z","steps":["trace[544993132] 'process raft request' (duration: 131.309453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.176626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.587007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:21:41.176669Z","caller":"traceutil/trace.go:171","msg":"trace[372096081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2248; }","duration":"100.694209ms","start":"2026-08-11T08:21:41.075964Z","end":"2026-08-11T08:21:41.176658Z","steps":["trace[372096081] 'agreement among raft nodes before linearized reading' (duration: 100.611237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.691755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905884127108594762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T08:21:41.755706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.290112ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:21:41.755809Z","caller":"traceutil/trace.go:171","msg":"trace[1192595730] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2248; }","duration":"329.408775ms","start":"2026-08-11T08:21:41.426384Z","end":"2026-08-11T08:21:41.755793Z","steps":["trace[1192595730] 'range keys from in-memory index tree' (duration: 329.274102ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:41.826814Z","caller":"traceutil/trace.go:171","msg":"trace[607465302] linearizableReadLoop","detail":"{readStateIndex:2344; appliedIndex:2343; }","duration":"635.518504ms","start":"2026-08-11T08:21:41.191261Z","end":"2026-08-11T08:21:41.82678Z","steps":["trace[607465302] 'read index received' (duration: 635.125816ms)","trace[607465302] 'applied index is now lower than readState.Index' (duration: 391.438µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:21:41.82711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.813919ms","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-08-11T08:21:41.827141Z","caller":"traceutil/trace.go:171","msg":"trace[2026021305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2249; }","duration":"635.885941ms","start":"2026-08-11T08:21:41.191243Z","end":"2026-08-11T08:21:41.827129Z","steps":["trace[2026021305] 'agreement among raft nodes before linearized reading' (duration: 635.688597ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:41.827101Z","caller":"traceutil/trace.go:171","msg":"trace[1361720746] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"645.29995ms","start":"2026-08-11T08:21:41.181733Z","end":"2026-08-11T08:21:41.827033Z","steps":["trace[1361720746] 'process raft request' (duration: 644.786141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.827225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.218571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.127\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-11T08:21:41.827314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.463527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T08:21:41.827321Z","caller":"traceutil/trace.go:171","msg":"trace[2036908268] range","detail":"{range_begin:/registry/masterleases/199.19.213.127; range_end:; response_count:1; response_revision:2249; }","duration":"140.366045ms","start":"2026-08-11T08:21:41.686936Z","end":"2026-08-11T08:21:41.827302Z","steps":["trace[2036908268] 'agreement among raft nodes before linearized reading' (duration: 140.157981ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:41.827341Z","caller":"traceutil/trace.go:171","msg":"trace[1767434762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2249; }","duration":"539.522588ms","start":"2026-08-11T08:21:41.28781Z","end":"2026-08-11T08:21:41.827332Z","steps":["trace[1767434762] 'agreement among raft nodes before linearized reading' (duration: 539.458757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.82736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:41.287791Z","time spent":"539.562019ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T08:21:41.827225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.487259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T08:21:41.827481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.496098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-11T08:21:41.82717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:41.191231Z","time spent":"635.932842ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T08:21:41.827543Z","caller":"traceutil/trace.go:171","msg":"trace[1903525203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2249; }","duration":"576.807346ms","start":"2026-08-11T08:21:41.250666Z","end":"2026-08-11T08:21:41.827473Z","steps":["trace[1903525203] 'agreement among raft nodes before linearized reading' (duration: 576.374257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.82811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:41.250644Z","time spent":"577.445989ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T08:21:41.827351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:41.181706Z","time spent":"645.514724ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:21:41.82763Z","caller":"traceutil/trace.go:171","msg":"trace[1565563770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2249; }","duration":"538.640222ms","start":"2026-08-11T08:21:41.288922Z","end":"2026-08-11T08:21:41.827563Z","steps":["trace[1565563770] 'agreement among raft nodes before linearized reading' (duration: 538.306914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:21:41.830266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:21:41.288912Z","time spent":"541.295985ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T08:21:42.292012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.36386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-08-11T08:21:42.292081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.698856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:21:42.292102Z","caller":"traceutil/trace.go:171","msg":"trace[1799876100] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2254; }","duration":"241.508093ms","start":"2026-08-11T08:21:42.050571Z","end":"2026-08-11T08:21:42.292079Z","steps":["trace[1799876100] 'range keys from in-memory index tree' (duration: 241.129135ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:21:42.292146Z","caller":"traceutil/trace.go:171","msg":"trace[1047896378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2254; }","duration":"215.820098ms","start":"2026-08-11T08:21:42.076309Z","end":"2026-08-11T08:21:42.292129Z","steps":["trace[1047896378] 'range keys from in-memory index tree' (duration: 215.549052ms)"],"step_count":1}