{"level":"warn","ts":"2026-05-11T05:38:53.386693Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:38:53.386859Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T05:38:53.38699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:38:53.387018Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-11T05:38:53.387064Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T05:38:53.388337Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-11T05:38:53.38848Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T05:38:54.62849Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"811.179788ms"} {"level":"info","ts":"2026-05-11T05:38:55.506081Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-11T05:38:55.506295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:38:55.506367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-11T05:38:55.506421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:38:55.506447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-11T05:38:55.506579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-11T05:38:55.512452Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:38:55.514793Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:38:55.516683Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T05:38:55.520124Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:38:55.520348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:38:55.52157Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:38:55.521661Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:38:55.52176Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:38:55.523255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-11T05:38:55.523425Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-11T05:38:55.525681Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T05:38:55.525782Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-11T05:38:55.525826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-11T05:38:55.526023Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:38:55.526074Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:38:56.307817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:38:56.307889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:38:56.307915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-11T05:38:56.307936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:38:56.307947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-11T05:38:56.307965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-11T05:38:56.307997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-11T05:38:56.308969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:38:56.309952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:38:56.309944Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:38:56.31001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:38:56.310298Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:38:56.310418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:38:56.310451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:38:56.310605Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:38:56.310689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:38:56.314918Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-11T05:38:56.315023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:39:29.813878Z","caller":"traceutil/trace.go:171","msg":"trace[1285127702] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"169.987357ms","start":"2026-05-11T05:39:29.64386Z","end":"2026-05-11T05:39:29.813848Z","steps":["trace[1285127702] 'process raft request' (duration: 167.701936ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:29.816942Z","caller":"traceutil/trace.go:171","msg":"trace[138123909] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"160.727418ms","start":"2026-05-11T05:39:29.656195Z","end":"2026-05-11T05:39:29.816922Z","steps":["trace[138123909] 'process raft request' (duration: 160.318608ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:30.457092Z","caller":"traceutil/trace.go:171","msg":"trace[1958430272] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"326.227836ms","start":"2026-05-11T05:39:30.130826Z","end":"2026-05-11T05:39:30.457054Z","steps":["trace[1958430272] 'process raft request' (duration: 325.667681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:30.457726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.130787Z","time spent":"326.436341ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:39:31.028628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.559776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:39:31.02894Z","caller":"traceutil/trace.go:171","msg":"trace[2057387900] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"712.655948ms","start":"2026-05-11T05:39:30.316218Z","end":"2026-05-11T05:39:31.028873Z","steps":["trace[2057387900] 'read index received' (duration: 139.895583ms)","trace[2057387900] 'applied index is now lower than readState.Index' (duration: 572.759305ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:31.02902Z","caller":"traceutil/trace.go:171","msg":"trace[105832890] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"894.45193ms","start":"2026-05-11T05:39:30.134556Z","end":"2026-05-11T05:39:31.029008Z","steps":["trace[105832890] 'process raft request' (duration: 718.159506ms)","trace[105832890] 'compare' (duration: 175.40158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:31.029089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.134529Z","time spent":"894.525223ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:31.029249Z","caller":"traceutil/trace.go:171","msg":"trace[295253170] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"800.756129ms","start":"2026-05-11T05:39:30.22845Z","end":"2026-05-11T05:39:31.029206Z","steps":["trace[295253170] 'process raft request' (duration: 800.328178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.029784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.566964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-drcbv\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-05-11T05:39:31.029833Z","caller":"traceutil/trace.go:171","msg":"trace[1632505972] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-drcbv; range_end:; response_count:1; response_revision:608; }","duration":"713.676956ms","start":"2026-05-11T05:39:30.316139Z","end":"2026-05-11T05:39:31.029816Z","steps":["trace[1632505972] 'agreement among raft nodes before linearized reading' (duration: 713.290935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.029826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.228415Z","time spent":"801.045707ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:39:31.029869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.316115Z","time spent":"713.746138ms","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-drcbv\" "} {"level":"info","ts":"2026-05-11T05:39:31.029402Z","caller":"traceutil/trace.go:171","msg":"trace[1978265451] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"607.628776ms","start":"2026-05-11T05:39:30.421761Z","end":"2026-05-11T05:39:31.02939Z","steps":["trace[1978265451] 'process raft request' (duration: 607.075191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.030269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.421717Z","time spent":"608.522939ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:39:31.529367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134898404,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:39:31.566247Z","caller":"traceutil/trace.go:171","msg":"trace[1961729653] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.015910431s","start":"2026-05-11T05:39:30.550285Z","end":"2026-05-11T05:39:31.566196Z","steps":["trace[1961729653] 'process raft request' (duration: 990.546474ms)","trace[1961729653] 'compare' (duration: 25.074399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:31.56646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.550263Z","time spent":"1.016090736s","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:39:31.566661Z","caller":"traceutil/trace.go:171","msg":"trace[1588851493] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"944.376621ms","start":"2026-05-11T05:39:30.622218Z","end":"2026-05-11T05:39:31.566595Z","steps":["trace[1588851493] 'process raft request' (duration: 943.844038ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:31.566754Z","caller":"traceutil/trace.go:171","msg":"trace[165496558] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"744.340484ms","start":"2026-05-11T05:39:30.822387Z","end":"2026-05-11T05:39:31.566727Z","steps":["trace[165496558] 'process raft request' (duration: 744.290703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.566902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.822347Z","time spent":"744.483748ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:39:31.567103Z","caller":"traceutil/trace.go:171","msg":"trace[1325148411] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"916.725823ms","start":"2026-05-11T05:39:30.650359Z","end":"2026-05-11T05:39:31.567085Z","steps":["trace[1325148411] 'process raft request' (duration: 915.82803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.567261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.650325Z","time spent":"916.853557ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:31.566863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.622177Z","time spent":"944.588568ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:39:31.572676Z","caller":"traceutil/trace.go:171","msg":"trace[1735278977] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:628; }","duration":"543.664587ms","start":"2026-05-11T05:39:31.028976Z","end":"2026-05-11T05:39:31.572641Z","steps":["trace[1735278977] 'read index received' (duration: 511.89067ms)","trace[1735278977] 'applied index is now lower than readState.Index' (duration: 31.772497ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:31.572818Z","caller":"traceutil/trace.go:171","msg":"trace[1805581841] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"147.179637ms","start":"2026-05-11T05:39:31.425613Z","end":"2026-05-11T05:39:31.572793Z","steps":["trace[1805581841] 'process raft request' (duration: 146.971362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.572935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.462583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:31.572986Z","caller":"traceutil/trace.go:171","msg":"trace[1751769615] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"532.550402ms","start":"2026-05-11T05:39:31.040428Z","end":"2026-05-11T05:39:31.572978Z","steps":["trace[1751769615] 'process raft request' (duration: 532.080719ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:31.572993Z","caller":"traceutil/trace.go:171","msg":"trace[627170495] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:615; }","duration":"552.631009ms","start":"2026-05-11T05:39:31.020345Z","end":"2026-05-11T05:39:31.572976Z","steps":["trace[627170495] 'agreement among raft nodes before linearized reading' (duration: 552.482504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.573099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:31.040418Z","time spent":"532.589253ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:31.573253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138547644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:39:31.573278Z","caller":"traceutil/trace.go:171","msg":"trace[2054464763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.138610875s","start":"2026-05-11T05:39:30.43466Z","end":"2026-05-11T05:39:31.573271Z","steps":["trace[2054464763] 'agreement among raft nodes before linearized reading' (duration: 1.138493942s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.573295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.434613Z","time spent":"1.138678887s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:39:31.573412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.061671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:31.57343Z","caller":"traceutil/trace.go:171","msg":"trace[820923041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"800.134023ms","start":"2026-05-11T05:39:30.773291Z","end":"2026-05-11T05:39:31.573425Z","steps":["trace[820923041] 'agreement among raft nodes before linearized reading' (duration: 800.103282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.573389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.885667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:31.57349Z","caller":"traceutil/trace.go:171","msg":"trace[1434327578] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:615; }","duration":"353.088572ms","start":"2026-05-11T05:39:31.220377Z","end":"2026-05-11T05:39:31.573465Z","steps":["trace[1434327578] 'agreement among raft nodes before linearized reading' (duration: 352.898977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.573552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:31.220323Z","time spent":"353.219235ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-11T05:39:31.573727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.758457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:39:31.573751Z","caller":"traceutil/trace.go:171","msg":"trace[237865382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:615; }","duration":"143.811468ms","start":"2026-05-11T05:39:31.429934Z","end":"2026-05-11T05:39:31.573745Z","steps":["trace[237865382] 'agreement among raft nodes before linearized reading' (duration: 143.723286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.573444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:30.773263Z","time spent":"800.178544ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:39:31.572958Z","caller":"traceutil/trace.go:171","msg":"trace[1441747980] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"533.945289ms","start":"2026-05-11T05:39:31.039003Z","end":"2026-05-11T05:39:31.572949Z","steps":["trace[1441747980] 'process raft request' (duration: 533.329543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:31.574313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:31.038975Z","time spent":"535.221543ms","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:31.580846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:31.020315Z","time spent":"552.71585ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-11T05:39:38.2824Z","caller":"traceutil/trace.go:171","msg":"trace[916538796] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"179.10197ms","start":"2026-05-11T05:39:38.103262Z","end":"2026-05-11T05:39:38.282364Z","steps":["trace[916538796] 'process raft request' (duration: 178.834103ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:38.312375Z","caller":"traceutil/trace.go:171","msg":"trace[579687493] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"208.881424ms","start":"2026-05-11T05:39:38.103441Z","end":"2026-05-11T05:39:38.312322Z","steps":["trace[579687493] 'process raft request' (duration: 208.524504ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:38.504594Z","caller":"traceutil/trace.go:171","msg":"trace[1850977448] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"145.169994ms","start":"2026-05-11T05:39:38.359391Z","end":"2026-05-11T05:39:38.504561Z","steps":["trace[1850977448] 'process raft request' (duration: 144.931348ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:39.170813Z","caller":"traceutil/trace.go:171","msg":"trace[1346516701] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"396.895402ms","start":"2026-05-11T05:39:38.773804Z","end":"2026-05-11T05:39:39.170699Z","steps":["trace[1346516701] 'read index received' (duration: 396.628626ms)","trace[1346516701] 'applied index is now lower than readState.Index' (duration: 265.406µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:39.170963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.140468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:39.171014Z","caller":"traceutil/trace.go:171","msg":"trace[244724434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"397.271581ms","start":"2026-05-11T05:39:38.773729Z","end":"2026-05-11T05:39:39.171001Z","steps":["trace[244724434] 'agreement among raft nodes before linearized reading' (duration: 397.170108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:39.171059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:38.773697Z","time spent":"397.353153ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:39:39.171146Z","caller":"traceutil/trace.go:171","msg":"trace[2106989172] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"571.643916ms","start":"2026-05-11T05:39:38.599457Z","end":"2026-05-11T05:39:39.171101Z","steps":["trace[2106989172] 'process raft request' (duration: 571.021859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:39.171368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:38.59943Z","time spent":"571.82175ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:40.155641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134898668,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:39:40.286765Z","caller":"traceutil/trace.go:171","msg":"trace[136207676] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"631.711878ms","start":"2026-05-11T05:39:39.655021Z","end":"2026-05-11T05:39:40.286733Z","steps":["trace[136207676] 'read index received' (duration: 631.336739ms)","trace[136207676] 'applied index is now lower than readState.Index' (duration: 373.669µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:40.287046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.794163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T05:39:40.287046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.004825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:39:40.287095Z","caller":"traceutil/trace.go:171","msg":"trace[1119895390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"627.919917ms","start":"2026-05-11T05:39:39.659162Z","end":"2026-05-11T05:39:40.287081Z","steps":["trace[1119895390] 'agreement among raft nodes before linearized reading' (duration: 627.740052ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.287105Z","caller":"traceutil/trace.go:171","msg":"trace[1707464260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"632.135429ms","start":"2026-05-11T05:39:39.654956Z","end":"2026-05-11T05:39:40.287091Z","steps":["trace[1707464260] 'agreement among raft nodes before linearized reading' (duration: 631.898372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.287131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:39.659129Z","time spent":"627.993889ms","remote":"127.0.0.1:51018","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-05-11T05:39:40.287137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:39.654928Z","time spent":"632.201781ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:39:40.287325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.102173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:40.287384Z","caller":"traceutil/trace.go:171","msg":"trace[547772023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"510.205356ms","start":"2026-05-11T05:39:39.777165Z","end":"2026-05-11T05:39:40.28737Z","steps":["trace[547772023] 'agreement among raft nodes before linearized reading' (duration: 510.112384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.287428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:39.777136Z","time spent":"510.281458ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:39:40.287441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:39.510293Z","time spent":"776.519832ms","remote":"127.0.0.1:50738","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-11T05:39:40.287707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.855315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:39:40.28782Z","caller":"traceutil/trace.go:171","msg":"trace[693616416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"628.05004ms","start":"2026-05-11T05:39:39.659745Z","end":"2026-05-11T05:39:40.287795Z","steps":["trace[693616416] 'agreement among raft nodes before linearized reading' (duration: 627.70604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.287879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:39.659708Z","time spent":"628.156844ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T05:39:40.643599Z","caller":"traceutil/trace.go:171","msg":"trace[1641587792] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"349.685992ms","start":"2026-05-11T05:39:40.293886Z","end":"2026-05-11T05:39:40.643572Z","steps":["trace[1641587792] 'process raft request' (duration: 349.625831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.643798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.293877Z","time spent":"349.811745ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:40.644087Z","caller":"traceutil/trace.go:171","msg":"trace[535994808] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"350.973906ms","start":"2026-05-11T05:39:40.293062Z","end":"2026-05-11T05:39:40.644036Z","steps":["trace[535994808] 'process raft request' (duration: 350.356401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.644169Z","caller":"traceutil/trace.go:171","msg":"trace[404900024] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"350.672678ms","start":"2026-05-11T05:39:40.293455Z","end":"2026-05-11T05:39:40.644127Z","steps":["trace[404900024] 'process raft request' (duration: 350.014511ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.644227Z","caller":"traceutil/trace.go:171","msg":"trace[1601955477] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:713; }","duration":"327.583792ms","start":"2026-05-11T05:39:40.31661Z","end":"2026-05-11T05:39:40.644194Z","steps":["trace[1601955477] 'read index received' (duration: 309.221621ms)","trace[1601955477] 'applied index is now lower than readState.Index' (duration: 18.357421ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:40.644143Z","caller":"traceutil/trace.go:171","msg":"trace[757486138] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"354.626654ms","start":"2026-05-11T05:39:40.289434Z","end":"2026-05-11T05:39:40.64406Z","steps":["trace[757486138] 'process raft request' (duration: 336.381266ms)","trace[757486138] 'compare' (duration: 17.383364ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:40.6443Z","caller":"traceutil/trace.go:171","msg":"trace[1970747584] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"348.522821ms","start":"2026-05-11T05:39:40.295764Z","end":"2026-05-11T05:39:40.644287Z","steps":["trace[1970747584] 'process raft request' (duration: 347.775171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.644402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.29342Z","time spent":"350.858203ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:40.64447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.295748Z","time spent":"348.659215ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:40.64448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.866549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T05:39:40.64452Z","caller":"traceutil/trace.go:171","msg":"trace[1672286387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"327.947611ms","start":"2026-05-11T05:39:40.316559Z","end":"2026-05-11T05:39:40.644506Z","steps":["trace[1672286387] 'agreement among raft nodes before linearized reading' (duration: 327.726445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.644347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.293036Z","time spent":"351.162701ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:40.644555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.316541Z","time spent":"328.006633ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T05:39:40.644752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:40.289394Z","time spent":"355.085135ms","remote":"127.0.0.1:50738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:49.55033Z","caller":"traceutil/trace.go:171","msg":"trace[348440615] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"107.216721ms","start":"2026-05-11T05:39:49.443079Z","end":"2026-05-11T05:39:49.550296Z","steps":["trace[348440615] 'process raft request' (duration: 106.888963ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:50.633753Z","caller":"traceutil/trace.go:171","msg":"trace[1653753384] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"117.24808ms","start":"2026-05-11T05:39:50.516482Z","end":"2026-05-11T05:39:50.63373Z","steps":["trace[1653753384] 'process raft request' (duration: 117.087945ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:52.96911Z","caller":"traceutil/trace.go:171","msg":"trace[1897868512] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"155.082416ms","start":"2026-05-11T05:39:52.813995Z","end":"2026-05-11T05:39:52.969077Z","steps":["trace[1897868512] 'process raft request' (duration: 154.911182ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:52.969801Z","caller":"traceutil/trace.go:171","msg":"trace[2118335496] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"155.700472ms","start":"2026-05-11T05:39:52.814082Z","end":"2026-05-11T05:39:52.969782Z","steps":["trace[2118335496] 'process raft request' (duration: 155.421295ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:52.969934Z","caller":"traceutil/trace.go:171","msg":"trace[78733345] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"155.838386ms","start":"2026-05-11T05:39:52.814083Z","end":"2026-05-11T05:39:52.969921Z","steps":["trace[78733345] 'process raft request' (duration: 155.661521ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:52.970057Z","caller":"traceutil/trace.go:171","msg":"trace[1989637138] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"155.047676ms","start":"2026-05-11T05:39:52.815002Z","end":"2026-05-11T05:39:52.97005Z","steps":["trace[1989637138] 'process raft request' (duration: 154.868691ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:52.970101Z","caller":"traceutil/trace.go:171","msg":"trace[1269891841] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"155.014095ms","start":"2026-05-11T05:39:52.815077Z","end":"2026-05-11T05:39:52.970091Z","steps":["trace[1269891841] 'process raft request' (duration: 154.933443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:57.492501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134898888,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:39:57.659905Z","caller":"traceutil/trace.go:171","msg":"trace[897502867] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"668.115017ms","start":"2026-05-11T05:39:56.991755Z","end":"2026-05-11T05:39:57.65987Z","steps":["trace[897502867] 'process raft request' (duration: 667.85789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:57.660161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:56.991742Z","time spent":"668.284782ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:58.026499Z","caller":"traceutil/trace.go:171","msg":"trace[1555926593] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:831; }","duration":"1.034108834s","start":"2026-05-11T05:39:56.992353Z","end":"2026-05-11T05:39:58.026461Z","steps":["trace[1555926593] 'read index received' (duration: 667.219683ms)","trace[1555926593] 'applied index is now lower than readState.Index' (duration: 366.886991ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:58.026618Z","caller":"traceutil/trace.go:171","msg":"trace[808518052] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.031623878s","start":"2026-05-11T05:39:56.994962Z","end":"2026-05-11T05:39:58.026585Z","steps":["trace[808518052] 'process raft request' (duration: 1.031333171s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:58.026874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:56.994949Z","time spent":"1.031777162s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:58.026987Z","caller":"traceutil/trace.go:171","msg":"trace[1482322189] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.032373849s","start":"2026-05-11T05:39:56.994574Z","end":"2026-05-11T05:39:58.026948Z","steps":["trace[1482322189] 'process raft request' (duration: 1.031466775s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:58.026923Z","caller":"traceutil/trace.go:171","msg":"trace[652101402] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"505.545863ms","start":"2026-05-11T05:39:57.521117Z","end":"2026-05-11T05:39:58.026663Z","steps":["trace[652101402] 'process raft request' (duration: 505.271306ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:58.027025Z","caller":"traceutil/trace.go:171","msg":"trace[1668750805] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.032099991s","start":"2026-05-11T05:39:56.994903Z","end":"2026-05-11T05:39:58.027003Z","steps":["trace[1668750805] 'process raft request' (duration: 1.031331871s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:58.027196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:56.994539Z","time spent":"1.032544753s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:58.027282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:57.521083Z","time spent":"506.036056ms","remote":"127.0.0.1:50988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:58.027414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:56.994888Z","time spent":"1.032235874s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:58.02759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.584695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:58.027673Z","caller":"traceutil/trace.go:171","msg":"trace[1274544100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"254.706298ms","start":"2026-05-11T05:39:57.772952Z","end":"2026-05-11T05:39:58.027659Z","steps":["trace[1274544100] 'agreement among raft nodes before linearized reading' (duration: 254.557724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:58.026884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034498765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T05:39:58.027867Z","caller":"traceutil/trace.go:171","msg":"trace[1562055615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"1.035553162s","start":"2026-05-11T05:39:56.992297Z","end":"2026-05-11T05:39:58.02785Z","steps":["trace[1562055615] 'agreement among raft nodes before linearized reading' (duration: 1.034400482s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:58.027912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:56.992271Z","time spent":"1.035626125s","remote":"127.0.0.1:51018","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-05-11T05:40:33.081407Z","caller":"traceutil/trace.go:171","msg":"trace[1625019786] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"308.564731ms","start":"2026-05-11T05:40:32.772821Z","end":"2026-05-11T05:40:33.081386Z","steps":["trace[1625019786] 'read index received' (duration: 308.509349ms)","trace[1625019786] 'applied index is now lower than readState.Index' (duration: 54.422µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:33.081531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.698554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:33.081509Z","caller":"traceutil/trace.go:171","msg":"trace[785488285] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"339.761954ms","start":"2026-05-11T05:40:32.741721Z","end":"2026-05-11T05:40:33.081482Z","steps":["trace[785488285] 'process raft request' (duration: 339.561989ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:33.081556Z","caller":"traceutil/trace.go:171","msg":"trace[1951386786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"308.763626ms","start":"2026-05-11T05:40:32.772785Z","end":"2026-05-11T05:40:33.081549Z","steps":["trace[1951386786] 'agreement among raft nodes before linearized reading' (duration: 308.685064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:33.081584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:32.772771Z","time spent":"308.808326ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:40:33.081644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:32.741701Z","time spent":"339.870187ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:33.583618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134899347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:40:33.678443Z","caller":"traceutil/trace.go:171","msg":"trace[1646575356] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"595.51772ms","start":"2026-05-11T05:40:33.082897Z","end":"2026-05-11T05:40:33.678414Z","steps":["trace[1646575356] 'read index received' (duration: 562.647372ms)","trace[1646575356] 'applied index is now lower than readState.Index' (duration: 32.869398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:33.678583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.668204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:33.678616Z","caller":"traceutil/trace.go:171","msg":"trace[575770462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"595.756586ms","start":"2026-05-11T05:40:33.08285Z","end":"2026-05-11T05:40:33.678607Z","steps":["trace[575770462] 'agreement among raft nodes before linearized reading' (duration: 595.668154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:33.678682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:33.082832Z","time spent":"595.842898ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:40:33.678977Z","caller":"traceutil/trace.go:171","msg":"trace[2114808087] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"862.379177ms","start":"2026-05-11T05:40:32.816584Z","end":"2026-05-11T05:40:33.678963Z","steps":["trace[2114808087] 'process raft request' (duration: 829.074488ms)","trace[2114808087] 'compare' (duration: 32.625311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:33.67907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:32.816563Z","time spent":"862.441629ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:34.084726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.012367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:40:34.084815Z","caller":"traceutil/trace.go:171","msg":"trace[1435905320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"252.216671ms","start":"2026-05-11T05:40:33.83258Z","end":"2026-05-11T05:40:34.084796Z","steps":["trace[1435905320] 'range keys from in-memory index tree' (duration: 251.777861ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:40.406912Z","caller":"traceutil/trace.go:171","msg":"trace[1658588348] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"112.894352ms","start":"2026-05-11T05:40:40.293991Z","end":"2026-05-11T05:40:40.406886Z","steps":["trace[1658588348] 'process raft request' (duration: 112.779969ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:40.407883Z","caller":"traceutil/trace.go:171","msg":"trace[1626228506] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"112.950214ms","start":"2026-05-11T05:40:40.294914Z","end":"2026-05-11T05:40:40.407864Z","steps":["trace[1626228506] 'process raft request' (duration: 112.78915ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:40.408202Z","caller":"traceutil/trace.go:171","msg":"trace[2008655216] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"113.186649ms","start":"2026-05-11T05:40:40.294972Z","end":"2026-05-11T05:40:40.408159Z","steps":["trace[2008655216] 'process raft request' (duration: 112.869232ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:50.049925Z","caller":"traceutil/trace.go:171","msg":"trace[986251102] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"441.243196ms","start":"2026-05-11T05:40:49.608641Z","end":"2026-05-11T05:40:50.049884Z","steps":["trace[986251102] 'read index received' (duration: 439.028497ms)","trace[986251102] 'applied index is now lower than readState.Index' (duration: 2.212619ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:50.049974Z","caller":"traceutil/trace.go:171","msg":"trace[1255792385] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"458.870051ms","start":"2026-05-11T05:40:49.591085Z","end":"2026-05-11T05:40:50.049955Z","steps":["trace[1255792385] 'process raft request' (duration: 456.646033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:50.050099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:49.591073Z","time spent":"458.955943ms","remote":"127.0.0.1:50738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:50.050136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.484093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:50.050191Z","caller":"traceutil/trace.go:171","msg":"trace[1609263240] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1077; }","duration":"441.577485ms","start":"2026-05-11T05:40:49.608595Z","end":"2026-05-11T05:40:50.050173Z","steps":["trace[1609263240] 'agreement among raft nodes before linearized reading' (duration: 441.450021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:50.050239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:49.608578Z","time spent":"441.645957ms","remote":"127.0.0.1:39718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:50.050352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.262744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:50.050519Z","caller":"traceutil/trace.go:171","msg":"trace[1721955305] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1077; }","duration":"400.520951ms","start":"2026-05-11T05:40:49.649967Z","end":"2026-05-11T05:40:50.050488Z","steps":["trace[1721955305] 'agreement among raft nodes before linearized reading' (duration: 400.220253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:50.050555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.48362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:50.05059Z","caller":"traceutil/trace.go:171","msg":"trace[788929821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"277.545811ms","start":"2026-05-11T05:40:49.773033Z","end":"2026-05-11T05:40:50.050579Z","steps":["trace[788929821] 'agreement among raft nodes before linearized reading' (duration: 277.49598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:50.050588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:49.649954Z","time spent":"400.616423ms","remote":"127.0.0.1:39772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:40:57.513173Z","caller":"traceutil/trace.go:171","msg":"trace[891569895] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"195.142992ms","start":"2026-05-11T05:40:57.318007Z","end":"2026-05-11T05:40:57.51315Z","steps":["trace[891569895] 'process raft request' (duration: 195.008398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:58.037038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.667481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:58.037123Z","caller":"traceutil/trace.go:171","msg":"trace[1023495689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"263.806745ms","start":"2026-05-11T05:40:57.773298Z","end":"2026-05-11T05:40:58.037105Z","steps":["trace[1023495689] 'range keys from in-memory index tree' (duration: 263.533578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:58.037529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.02465ms","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-05-11T05:40:58.037727Z","caller":"traceutil/trace.go:171","msg":"trace[676338403] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1103; }","duration":"361.229075ms","start":"2026-05-11T05:40:57.676483Z","end":"2026-05-11T05:40:58.037712Z","steps":["trace[676338403] 'range keys from in-memory index tree' (duration: 361.013988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:59.385945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.951919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:40:59.386062Z","caller":"traceutil/trace.go:171","msg":"trace[919342487] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"719.193379ms","start":"2026-05-11T05:40:58.666851Z","end":"2026-05-11T05:40:59.386045Z","steps":["trace[919342487] 'read index received' (duration: 415.942493ms)","trace[919342487] 'applied index is now lower than readState.Index' (duration: 303.249716ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:59.386294Z","caller":"traceutil/trace.go:171","msg":"trace[1509329612] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"742.051288ms","start":"2026-05-11T05:40:58.644226Z","end":"2026-05-11T05:40:59.386278Z","steps":["trace[1509329612] 'process raft request' (duration: 438.672699ms)","trace[1509329612] 'compare' (duration: 302.612299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:59.386365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:58.644191Z","time spent":"742.14216ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:59.386539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.669251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:40:59.386714Z","caller":"traceutil/trace.go:171","msg":"trace[689786492] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1108; }","duration":"719.909599ms","start":"2026-05-11T05:40:58.666784Z","end":"2026-05-11T05:40:59.386693Z","steps":["trace[689786492] 'agreement among raft nodes before linearized reading' (duration: 719.609631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:59.386768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:58.666714Z","time spent":"720.041432ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:59.386745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.928666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:59.387467Z","caller":"traceutil/trace.go:171","msg":"trace[2067809798] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1108; }","duration":"345.697456ms","start":"2026-05-11T05:40:59.041725Z","end":"2026-05-11T05:40:59.387422Z","steps":["trace[2067809798] 'agreement among raft nodes before linearized reading' (duration: 344.758751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:59.387529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:59.041702Z","time spent":"345.807989ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:40:59.751327Z","caller":"traceutil/trace.go:171","msg":"trace[943073066] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"232.467024ms","start":"2026-05-11T05:40:59.518834Z","end":"2026-05-11T05:40:59.751301Z","steps":["trace[943073066] 'process raft request' (duration: 211.940126ms)","trace[943073066] 'compare' (duration: 20.27271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:59.751433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.400794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T05:40:59.751556Z","caller":"traceutil/trace.go:171","msg":"trace[465732014] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1108; }","duration":"237.65334ms","start":"2026-05-11T05:40:59.513865Z","end":"2026-05-11T05:40:59.751518Z","steps":["trace[465732014] 'range keys from in-memory index tree' (duration: 237.185006ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:59.92106Z","caller":"traceutil/trace.go:171","msg":"trace[1680851390] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"137.331854ms","start":"2026-05-11T05:40:59.783707Z","end":"2026-05-11T05:40:59.921039Z","steps":["trace[1680851390] 'process raft request' (duration: 137.154399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:01.140476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.697934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:01.140558Z","caller":"traceutil/trace.go:171","msg":"trace[823848072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"367.830457ms","start":"2026-05-11T05:41:00.772709Z","end":"2026-05-11T05:41:01.140539Z","steps":["trace[823848072] 'range keys from in-memory index tree' (duration: 367.550379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:01.140596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:00.772686Z","time spent":"367.902419ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:41:01.140729Z","caller":"traceutil/trace.go:171","msg":"trace[512065986] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"326.623818ms","start":"2026-05-11T05:41:00.814085Z","end":"2026-05-11T05:41:01.140709Z","steps":["trace[512065986] 'process raft request' (duration: 278.132439ms)","trace[512065986] 'compare' (duration: 48.062308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:01.140838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:00.814057Z","time spent":"326.71958ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:41:02.463733Z","caller":"traceutil/trace.go:171","msg":"trace[1176483277] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"146.868893ms","start":"2026-05-11T05:41:02.316845Z","end":"2026-05-11T05:41:02.463714Z","steps":["trace[1176483277] 'process raft request' (duration: 146.75021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:02.779977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.589737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T05:41:02.780067Z","caller":"traceutil/trace.go:171","msg":"trace[1331969726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"151.70649ms","start":"2026-05-11T05:41:02.62834Z","end":"2026-05-11T05:41:02.780047Z","steps":["trace[1331969726] 'range keys from in-memory index tree' (duration: 151.408982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:02.78007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.980556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T05:41:02.78014Z","caller":"traceutil/trace.go:171","msg":"trace[395937958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"160.093469ms","start":"2026-05-11T05:41:02.620028Z","end":"2026-05-11T05:41:02.780121Z","steps":["trace[395937958] 'range keys from in-memory index tree' (duration: 159.832703ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:03.073108Z","caller":"traceutil/trace.go:171","msg":"trace[1755896535] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1158; }","duration":"245.996827ms","start":"2026-05-11T05:41:02.827086Z","end":"2026-05-11T05:41:03.073083Z","steps":["trace[1755896535] 'read index received' (duration: 35.316664ms)","trace[1755896535] 'applied index is now lower than readState.Index' (duration: 210.679213ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:03.073356Z","caller":"traceutil/trace.go:171","msg":"trace[1526607347] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"289.26545ms","start":"2026-05-11T05:41:02.784061Z","end":"2026-05-11T05:41:03.073326Z","steps":["trace[1526607347] 'process raft request' (duration: 202.237692ms)","trace[1526607347] 'compare' (duration: 86.574256ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:03.073425Z","caller":"traceutil/trace.go:171","msg":"trace[792412719] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"288.400767ms","start":"2026-05-11T05:41:02.784966Z","end":"2026-05-11T05:41:03.073367Z","steps":["trace[792412719] 'process raft request' (duration: 288.066258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:03.073589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.48776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:03.073646Z","caller":"traceutil/trace.go:171","msg":"trace[501772009] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1121; }","duration":"246.600773ms","start":"2026-05-11T05:41:02.82703Z","end":"2026-05-11T05:41:03.073631Z","steps":["trace[501772009] 'agreement among raft nodes before linearized reading' (duration: 246.154651ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:14.989921Z","caller":"traceutil/trace.go:171","msg":"trace[327307142] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"451.485455ms","start":"2026-05-11T05:41:14.538412Z","end":"2026-05-11T05:41:14.989898Z","steps":["trace[327307142] 'read index received' (duration: 451.322161ms)","trace[327307142] 'applied index is now lower than readState.Index' (duration: 162.284µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:14.990034Z","caller":"traceutil/trace.go:171","msg":"trace[1296613465] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"528.051259ms","start":"2026-05-11T05:41:14.46196Z","end":"2026-05-11T05:41:14.990011Z","steps":["trace[1296613465] 'process raft request' (duration: 527.786732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:14.990155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:14.461936Z","time spent":"528.159162ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:14.990334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.901346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:41:14.990442Z","caller":"traceutil/trace.go:171","msg":"trace[958620102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"452.05681ms","start":"2026-05-11T05:41:14.538368Z","end":"2026-05-11T05:41:14.990425Z","steps":["trace[958620102] 'agreement among raft nodes before linearized reading' (duration: 451.742481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:14.990505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:14.538346Z","time spent":"452.147573ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:41:14.990607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.278002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:14.990698Z","caller":"traceutil/trace.go:171","msg":"trace[979391720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"218.407986ms","start":"2026-05-11T05:41:14.772278Z","end":"2026-05-11T05:41:14.990686Z","steps":["trace[979391720] 'agreement among raft nodes before linearized reading' (duration: 217.771059ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:27.728026Z","caller":"traceutil/trace.go:171","msg":"trace[1039905575] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"180.73795ms","start":"2026-05-11T05:41:27.547261Z","end":"2026-05-11T05:41:27.727999Z","steps":["trace[1039905575] 'process raft request' (duration: 180.578146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:30.022686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.81432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tp5rp\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-11T05:41:30.022758Z","caller":"traceutil/trace.go:171","msg":"trace[865551095] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tp5rp; range_end:; response_count:1; response_revision:1273; }","duration":"178.928233ms","start":"2026-05-11T05:41:29.843815Z","end":"2026-05-11T05:41:30.022743Z","steps":["trace[865551095] 'range keys from in-memory index tree' (duration: 178.641415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:30.023353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.92621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:30.023481Z","caller":"traceutil/trace.go:171","msg":"trace[523812655] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"172.398533ms","start":"2026-05-11T05:41:29.851062Z","end":"2026-05-11T05:41:30.023461Z","steps":["trace[523812655] 'process raft request' (duration: 63.266586ms)","trace[523812655] 'compare' (duration: 108.637183ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:35.474158Z","caller":"traceutil/trace.go:171","msg":"trace[666045464] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"373.407101ms","start":"2026-05-11T05:41:35.100726Z","end":"2026-05-11T05:41:35.474133Z","steps":["trace[666045464] 'read index received' (duration: 373.086673ms)","trace[666045464] 'applied index is now lower than readState.Index' (duration: 319.228µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:35.474195Z","caller":"traceutil/trace.go:171","msg":"trace[1355076340] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"387.276475ms","start":"2026-05-11T05:41:35.086889Z","end":"2026-05-11T05:41:35.474166Z","steps":["trace[1355076340] 'process raft request' (duration: 386.980578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.474435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.455622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-11T05:41:35.47443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.658523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T05:41:35.474469Z","caller":"traceutil/trace.go:171","msg":"trace[321070195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"253.517664ms","start":"2026-05-11T05:41:35.220943Z","end":"2026-05-11T05:41:35.474461Z","steps":["trace[321070195] 'agreement among raft nodes before linearized reading' (duration: 253.392921ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:35.474489Z","caller":"traceutil/trace.go:171","msg":"trace[306882133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"372.748226ms","start":"2026-05-11T05:41:35.101724Z","end":"2026-05-11T05:41:35.474472Z","steps":["trace[306882133] 'agreement among raft nodes before linearized reading' (duration: 372.565071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.474489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.319005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-11T05:41:35.474536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.101713Z","time spent":"372.811587ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T05:41:35.474602Z","caller":"traceutil/trace.go:171","msg":"trace[135767066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"254.450019ms","start":"2026-05-11T05:41:35.220112Z","end":"2026-05-11T05:41:35.474562Z","steps":["trace[135767066] 'agreement among raft nodes before linearized reading' (duration: 254.258274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.474622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.101641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:35.474764Z","caller":"traceutil/trace.go:171","msg":"trace[1380972888] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1315; }","duration":"293.311936ms","start":"2026-05-11T05:41:35.181424Z","end":"2026-05-11T05:41:35.474736Z","steps":["trace[1380972888] 'agreement among raft nodes before linearized reading' (duration: 293.023519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.474898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.086866Z","time spent":"387.435169ms","remote":"127.0.0.1:50918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:35.475325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.598432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T05:41:35.475377Z","caller":"traceutil/trace.go:171","msg":"trace[712908303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"374.685175ms","start":"2026-05-11T05:41:35.100679Z","end":"2026-05-11T05:41:35.475364Z","steps":["trace[712908303] 'agreement among raft nodes before linearized reading' (duration: 373.557315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.475535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.10066Z","time spent":"374.85982ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:41:36.063663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.287022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:36.063769Z","caller":"traceutil/trace.go:171","msg":"trace[885640059] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"323.32371ms","start":"2026-05-11T05:41:35.740429Z","end":"2026-05-11T05:41:36.063753Z","steps":["trace[885640059] 'read index received' (duration: 44.941µs)","trace[885640059] 'applied index is now lower than readState.Index' (duration: 323.277629ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:36.063962Z","caller":"traceutil/trace.go:171","msg":"trace[1919600706] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"516.962729ms","start":"2026-05-11T05:41:35.546985Z","end":"2026-05-11T05:41:36.063948Z","steps":["trace[1919600706] 'process raft request' (duration: 58.318997ms)","trace[1919600706] 'compare' (duration: 458.18361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:36.064031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.546968Z","time spent":"517.033411ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:36.06409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.117662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:36.064203Z","caller":"traceutil/trace.go:171","msg":"trace[69080000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"290.304157ms","start":"2026-05-11T05:41:35.773879Z","end":"2026-05-11T05:41:36.064183Z","steps":["trace[69080000] 'agreement among raft nodes before linearized reading' (duration: 290.085781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:36.064513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.077641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:36.064557Z","caller":"traceutil/trace.go:171","msg":"trace[1740096104] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1320; }","duration":"324.161403ms","start":"2026-05-11T05:41:35.740385Z","end":"2026-05-11T05:41:36.064547Z","steps":["trace[1740096104] 'agreement among raft nodes before linearized reading' (duration: 324.099671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:36.064595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.740366Z","time spent":"324.221385ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:41:36.064906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.539708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:41:36.064971Z","caller":"traceutil/trace.go:171","msg":"trace[855118528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"192.682462ms","start":"2026-05-11T05:41:35.872273Z","end":"2026-05-11T05:41:36.064956Z","steps":["trace[855118528] 'agreement among raft nodes before linearized reading' (duration: 192.253211ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:36.6549Z","caller":"traceutil/trace.go:171","msg":"trace[533204544] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"584.130078ms","start":"2026-05-11T05:41:36.070748Z","end":"2026-05-11T05:41:36.654878Z","steps":["trace[533204544] 'process raft request' (duration: 583.970794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:36.655029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:36.070735Z","time spent":"584.228ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:41:36.655249Z","caller":"traceutil/trace.go:171","msg":"trace[1154309147] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"539.971411ms","start":"2026-05-11T05:41:36.11524Z","end":"2026-05-11T05:41:36.655211Z","steps":["trace[1154309147] 'process raft request' (duration: 539.603342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:36.655422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:36.115217Z","time spent":"540.108354ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:37.771092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.686681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T05:41:37.771191Z","caller":"traceutil/trace.go:171","msg":"trace[980329962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"200.850276ms","start":"2026-05-11T05:41:37.570329Z","end":"2026-05-11T05:41:37.77118Z","steps":["trace[980329962] 'range keys from in-memory index tree' (duration: 200.571828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:37.771149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.967519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T05:41:37.771321Z","caller":"traceutil/trace.go:171","msg":"trace[636645769] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"197.049607ms","start":"2026-05-11T05:41:37.574244Z","end":"2026-05-11T05:41:37.771293Z","steps":["trace[636645769] 'process raft request' (duration: 196.319297ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:37.771317Z","caller":"traceutil/trace.go:171","msg":"trace[1199704570] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"107.564054ms","start":"2026-05-11T05:41:37.663722Z","end":"2026-05-11T05:41:37.771287Z","steps":["trace[1199704570] 'read index received' (duration: 106.726592ms)","trace[1199704570] 'applied index is now lower than readState.Index' (duration: 835.652µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:37.771407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.682678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:37.771438Z","caller":"traceutil/trace.go:171","msg":"trace[2015353621] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1325; }","duration":"107.744459ms","start":"2026-05-11T05:41:37.663686Z","end":"2026-05-11T05:41:37.77143Z","steps":["trace[2015353621] 'agreement among raft nodes before linearized reading' (duration: 107.692938ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:37.771319Z","caller":"traceutil/trace.go:171","msg":"trace[111979726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"201.147533ms","start":"2026-05-11T05:41:37.570132Z","end":"2026-05-11T05:41:37.771279Z","steps":["trace[111979726] 'range keys from in-memory index tree' (duration: 200.893057ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:37.911398Z","caller":"traceutil/trace.go:171","msg":"trace[1380521361] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"135.870776ms","start":"2026-05-11T05:41:37.775476Z","end":"2026-05-11T05:41:37.911347Z","steps":["trace[1380521361] 'process raft request' (duration: 112.874074ms)","trace[1380521361] 'compare' (duration: 22.802766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:38.594892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.980325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:38.595097Z","caller":"traceutil/trace.go:171","msg":"trace[747533183] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"210.223541ms","start":"2026-05-11T05:41:38.384809Z","end":"2026-05-11T05:41:38.595032Z","steps":["trace[747533183] 'process raft request' (duration: 51.995931ms)","trace[747533183] 'compare' (duration: 157.759738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:39.099585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900055,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:41:39.132991Z","caller":"traceutil/trace.go:171","msg":"trace[1247453527] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"611.866473ms","start":"2026-05-11T05:41:38.521107Z","end":"2026-05-11T05:41:39.132974Z","steps":["trace[1247453527] 'process raft request' (duration: 611.77711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.133089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:38.521082Z","time spent":"611.964206ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:41:39.142224Z","caller":"traceutil/trace.go:171","msg":"trace[873434028] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1382; }","duration":"542.9743ms","start":"2026-05-11T05:41:38.59923Z","end":"2026-05-11T05:41:39.142204Z","steps":["trace[873434028] 'read index received' (duration: 533.749229ms)","trace[873434028] 'applied index is now lower than readState.Index' (duration: 9.224061ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:39.142327Z","caller":"traceutil/trace.go:171","msg":"trace[612153551] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"540.882775ms","start":"2026-05-11T05:41:38.601429Z","end":"2026-05-11T05:41:39.142312Z","steps":["trace[612153551] 'process raft request' (duration: 540.632808ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:39.142369Z","caller":"traceutil/trace.go:171","msg":"trace[666430466] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"481.285396ms","start":"2026-05-11T05:41:38.661025Z","end":"2026-05-11T05:41:39.14231Z","steps":["trace[666430466] 'process raft request' (duration: 481.125262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.14242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:38.601413Z","time spent":"540.940457ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:41:39.142438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.197455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-11T05:41:39.142492Z","caller":"traceutil/trace.go:171","msg":"trace[1959178694] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"543.294918ms","start":"2026-05-11T05:41:38.599184Z","end":"2026-05-11T05:41:39.142479Z","steps":["trace[1959178694] 'agreement among raft nodes before linearized reading' (duration: 543.178405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.142499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:38.661002Z","time spent":"481.429809ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:39.142517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:38.599166Z","time spent":"543.346189ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:41:39.148906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.777699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:41:39.148971Z","caller":"traceutil/trace.go:171","msg":"trace[2096140200] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1336; }","duration":"123.900302ms","start":"2026-05-11T05:41:39.025056Z","end":"2026-05-11T05:41:39.148956Z","steps":["trace[2096140200] 'agreement among raft nodes before linearized reading' (duration: 123.772699ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:46.867967Z","caller":"traceutil/trace.go:171","msg":"trace[567192196] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"192.559319ms","start":"2026-05-11T05:41:46.675373Z","end":"2026-05-11T05:41:46.867932Z","steps":["trace[567192196] 'process raft request' (duration: 192.258362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:47.644463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.592027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:47.644559Z","caller":"traceutil/trace.go:171","msg":"trace[114845923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1384; }","duration":"540.721ms","start":"2026-05-11T05:41:47.103808Z","end":"2026-05-11T05:41:47.644529Z","steps":["trace[114845923] 'range keys from in-memory index tree' (duration: 540.509064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:47.644665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:47.103786Z","time spent":"540.806022ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:41:47.644737Z","caller":"traceutil/trace.go:171","msg":"trace[1208254086] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"210.614911ms","start":"2026-05-11T05:41:47.434096Z","end":"2026-05-11T05:41:47.644711Z","steps":["trace[1208254086] 'read index received' (duration: 123.377738ms)","trace[1208254086] 'applied index is now lower than readState.Index' (duration: 87.236232ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:47.644851Z","caller":"traceutil/trace.go:171","msg":"trace[907871247] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"474.17974ms","start":"2026-05-11T05:41:47.170659Z","end":"2026-05-11T05:41:47.644838Z","steps":["trace[907871247] 'process raft request' (duration: 386.901885ms)","trace[907871247] 'compare' (duration: 86.966616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:47.644965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:47.170614Z","time spent":"474.262072ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:47.645055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.976481ms","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-05-11T05:41:47.645089Z","caller":"traceutil/trace.go:171","msg":"trace[371136822] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1385; }","duration":"211.045902ms","start":"2026-05-11T05:41:47.434032Z","end":"2026-05-11T05:41:47.645078Z","steps":["trace[371136822] 'agreement among raft nodes before linearized reading' (duration: 210.916898ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:47.776783Z","caller":"traceutil/trace.go:171","msg":"trace[866153380] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"127.303381ms","start":"2026-05-11T05:41:47.649451Z","end":"2026-05-11T05:41:47.776754Z","steps":["trace[866153380] 'process raft request' (duration: 110.125722ms)","trace[866153380] 'compare' (duration: 17.079657ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:47.778302Z","caller":"traceutil/trace.go:171","msg":"trace[287349336] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"128.03446ms","start":"2026-05-11T05:41:47.650254Z","end":"2026-05-11T05:41:47.778288Z","steps":["trace[287349336] 'process raft request' (duration: 127.717013ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:14.020607Z","caller":"traceutil/trace.go:171","msg":"trace[1368538060] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1562; }","duration":"113.629199ms","start":"2026-05-11T05:42:13.90695Z","end":"2026-05-11T05:42:14.020579Z","steps":["trace[1368538060] 'read index received' (duration: 113.401933ms)","trace[1368538060] 'applied index is now lower than readState.Index' (duration: 225.956µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:14.020988Z","caller":"traceutil/trace.go:171","msg":"trace[1961728881] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"115.482218ms","start":"2026-05-11T05:42:13.905491Z","end":"2026-05-11T05:42:14.020973Z","steps":["trace[1961728881] 'process raft request' (duration: 114.948284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:14.021303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.331148ms","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-05-11T05:42:14.021337Z","caller":"traceutil/trace.go:171","msg":"trace[118305931] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1507; }","duration":"114.395169ms","start":"2026-05-11T05:42:13.906931Z","end":"2026-05-11T05:42:14.021326Z","steps":["trace[118305931] 'agreement among raft nodes before linearized reading' (duration: 114.268746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:24.262316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.533683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T05:42:24.26241Z","caller":"traceutil/trace.go:171","msg":"trace[645191872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1552; }","duration":"202.669986ms","start":"2026-05-11T05:42:24.059719Z","end":"2026-05-11T05:42:24.262389Z","steps":["trace[645191872] 'range keys from in-memory index tree' (duration: 202.352549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:24.262714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.696577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-11T05:42:24.262783Z","caller":"traceutil/trace.go:171","msg":"trace[601621425] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1552; }","duration":"184.78535ms","start":"2026-05-11T05:42:24.07797Z","end":"2026-05-11T05:42:24.262756Z","steps":["trace[601621425] 'range keys from in-memory index tree' (duration: 184.613145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:24.262835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.607709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T05:42:24.26296Z","caller":"traceutil/trace.go:171","msg":"trace[1062569629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1552; }","duration":"196.740602ms","start":"2026-05-11T05:42:24.066176Z","end":"2026-05-11T05:42:24.262916Z","steps":["trace[1062569629] 'range keys from in-memory index tree' (duration: 196.379583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:30.48804Z","caller":"traceutil/trace.go:171","msg":"trace[1267902031] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"190.940791ms","start":"2026-05-11T05:42:30.297076Z","end":"2026-05-11T05:42:30.488017Z","steps":["trace[1267902031] 'process raft request' (duration: 190.873129ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:30.488147Z","caller":"traceutil/trace.go:171","msg":"trace[1691168317] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1641; }","duration":"191.242928ms","start":"2026-05-11T05:42:30.29688Z","end":"2026-05-11T05:42:30.488122Z","steps":["trace[1691168317] 'read index received' (duration: 60.270116ms)","trace[1691168317] 'applied index is now lower than readState.Index' (duration: 130.971042ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:30.488205Z","caller":"traceutil/trace.go:171","msg":"trace[64920710] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"191.443463ms","start":"2026-05-11T05:42:30.296624Z","end":"2026-05-11T05:42:30.488067Z","steps":["trace[64920710] 'process raft request' (duration: 126.26709ms)","trace[64920710] 'compare' (duration: 64.944057ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:30.48838Z","caller":"traceutil/trace.go:171","msg":"trace[2132677021] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"190.5675ms","start":"2026-05-11T05:42:30.297794Z","end":"2026-05-11T05:42:30.488362Z","steps":["trace[2132677021] 'process raft request' (duration: 190.191551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:30.488924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.005128ms","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-05-11T05:42:30.488994Z","caller":"traceutil/trace.go:171","msg":"trace[323598272] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1585; }","duration":"192.115061ms","start":"2026-05-11T05:42:30.296863Z","end":"2026-05-11T05:42:30.488978Z","steps":["trace[323598272] 'agreement among raft nodes before linearized reading' (duration: 191.354921ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.816837Z","caller":"traceutil/trace.go:171","msg":"trace[1307366858] linearizableReadLoop","detail":"{readStateIndex:1678; appliedIndex:1677; }","duration":"389.320235ms","start":"2026-05-11T05:42:38.427493Z","end":"2026-05-11T05:42:38.816813Z","steps":["trace[1307366858] 'read index received' (duration: 389.1574ms)","trace[1307366858] 'applied index is now lower than readState.Index' (duration: 161.945µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:38.816886Z","caller":"traceutil/trace.go:171","msg":"trace[499086491] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"392.372696ms","start":"2026-05-11T05:42:38.424488Z","end":"2026-05-11T05:42:38.81686Z","steps":["trace[499086491] 'process raft request' (duration: 392.18331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:38.817103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.600972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T05:42:38.817183Z","caller":"traceutil/trace.go:171","msg":"trace[1940819150] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1617; }","duration":"389.712886ms","start":"2026-05-11T05:42:38.427456Z","end":"2026-05-11T05:42:38.817169Z","steps":["trace[1940819150] 'agreement among raft nodes before linearized reading' (duration: 389.48923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:38.817241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:38.424468Z","time spent":"392.474618ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:38.817253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:38.427447Z","time spent":"389.762488ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:42:38.817254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.137588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-11T05:42:38.817274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.547346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:42:38.817355Z","caller":"traceutil/trace.go:171","msg":"trace[2035714573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1617; }","duration":"173.657389ms","start":"2026-05-11T05:42:38.643679Z","end":"2026-05-11T05:42:38.817336Z","steps":["trace[2035714573] 'agreement among raft nodes before linearized reading' (duration: 173.458044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:38.817345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.577192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T05:42:38.817366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.755889ms","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-05-11T05:42:38.817415Z","caller":"traceutil/trace.go:171","msg":"trace[786584921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1617; }","duration":"174.701765ms","start":"2026-05-11T05:42:38.642697Z","end":"2026-05-11T05:42:38.817399Z","steps":["trace[786584921] 'agreement among raft nodes before linearized reading' (duration: 174.47269ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.817424Z","caller":"traceutil/trace.go:171","msg":"trace[507175222] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1617; }","duration":"171.83095ms","start":"2026-05-11T05:42:38.645575Z","end":"2026-05-11T05:42:38.817406Z","steps":["trace[507175222] 'agreement among raft nodes before linearized reading' (duration: 171.645646ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.817423Z","caller":"traceutil/trace.go:171","msg":"trace[2137369248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1617; }","duration":"175.367583ms","start":"2026-05-11T05:42:38.642032Z","end":"2026-05-11T05:42:38.817399Z","steps":["trace[2137369248] 'agreement among raft nodes before linearized reading' (duration: 174.939103ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.993209Z","caller":"traceutil/trace.go:171","msg":"trace[1105864111] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"170.714852ms","start":"2026-05-11T05:42:38.822468Z","end":"2026-05-11T05:42:38.993183Z","steps":["trace[1105864111] 'process raft request' (duration: 126.582279ms)","trace[1105864111] 'compare' (duration: 43.977719ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:38.993574Z","caller":"traceutil/trace.go:171","msg":"trace[330068662] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1678; }","duration":"155.899115ms","start":"2026-05-11T05:42:38.837649Z","end":"2026-05-11T05:42:38.993548Z","steps":["trace[330068662] 'read index received' (duration: 25.894907ms)","trace[330068662] 'applied index is now lower than readState.Index' (duration: 130.002958ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:38.993735Z","caller":"traceutil/trace.go:171","msg":"trace[1556566127] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"171.131613ms","start":"2026-05-11T05:42:38.822592Z","end":"2026-05-11T05:42:38.993724Z","steps":["trace[1556566127] 'process raft request' (duration: 170.799715ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.993843Z","caller":"traceutil/trace.go:171","msg":"trace[1206361379] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"171.241285ms","start":"2026-05-11T05:42:38.822585Z","end":"2026-05-11T05:42:38.993826Z","steps":["trace[1206361379] 'process raft request' (duration: 170.557337ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:38.994112Z","caller":"traceutil/trace.go:171","msg":"trace[1309693004] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"170.661421ms","start":"2026-05-11T05:42:38.823439Z","end":"2026-05-11T05:42:38.994101Z","steps":["trace[1309693004] 'process raft request' (duration: 170.067405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:38.994339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.678286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:42:38.994378Z","caller":"traceutil/trace.go:171","msg":"trace[1320077358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1622; }","duration":"156.756688ms","start":"2026-05-11T05:42:38.837611Z","end":"2026-05-11T05:42:38.994368Z","steps":["trace[1320077358] 'agreement among raft nodes before linearized reading' (duration: 156.627065ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:39.121637Z","caller":"traceutil/trace.go:171","msg":"trace[198209999] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"123.542948ms","start":"2026-05-11T05:42:38.998064Z","end":"2026-05-11T05:42:39.121607Z","steps":["trace[198209999] 'process raft request' (duration: 117.812719ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:45.123712Z","caller":"traceutil/trace.go:171","msg":"trace[1680675288] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"101.075051ms","start":"2026-05-11T05:42:45.022612Z","end":"2026-05-11T05:42:45.123687Z","steps":["trace[1680675288] 'process raft request' (duration: 100.911957ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:45.124018Z","caller":"traceutil/trace.go:171","msg":"trace[1173726139] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"100.62873ms","start":"2026-05-11T05:42:45.023366Z","end":"2026-05-11T05:42:45.123995Z","steps":["trace[1173726139] 'process raft request' (duration: 100.281841ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:45.124202Z","caller":"traceutil/trace.go:171","msg":"trace[107137158] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"100.076826ms","start":"2026-05-11T05:42:45.02409Z","end":"2026-05-11T05:42:45.124167Z","steps":["trace[107137158] 'process raft request' (duration: 99.82429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:54.271902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:42:54.773099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:42:55.230813Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.4784357s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T05:42:55.231305Z","caller":"traceutil/trace.go:171","msg":"trace[1707996790] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"1.479019296s","start":"2026-05-11T05:42:53.752258Z","end":"2026-05-11T05:42:55.231277Z","steps":["trace[1707996790] 'process raft request' (duration: 1.478852191s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.231489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:53.752232Z","time spent":"1.479168169s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:55.232209Z","caller":"traceutil/trace.go:171","msg":"trace[1866396801] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"1.220520538s","start":"2026-05-11T05:42:54.011653Z","end":"2026-05-11T05:42:55.232174Z","steps":["trace[1866396801] 'process raft request' (duration: 1.220284062s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.232506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:54.011631Z","time spent":"1.220691983s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:55.234151Z","caller":"traceutil/trace.go:171","msg":"trace[359123391] linearizableReadLoop","detail":"{readStateIndex:1753; appliedIndex:1751; }","duration":"1.463041548s","start":"2026-05-11T05:42:53.771087Z","end":"2026-05-11T05:42:55.234129Z","steps":["trace[359123391] 'read index received' (duration: 1.459996387s)","trace[359123391] 'applied index is now lower than readState.Index' (duration: 3.043831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:55.234503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463402496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:55.234547Z","caller":"traceutil/trace.go:171","msg":"trace[823587741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1689; }","duration":"1.463492299s","start":"2026-05-11T05:42:53.771045Z","end":"2026-05-11T05:42:55.234537Z","steps":["trace[823587741] 'agreement among raft nodes before linearized reading' (duration: 1.463413507s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.234583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:53.771026Z","time spent":"1.463548161s","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:42:55.234847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349428639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T05:42:55.234887Z","caller":"traceutil/trace.go:171","msg":"trace[1822581516] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1689; }","duration":"1.34946758s","start":"2026-05-11T05:42:53.88541Z","end":"2026-05-11T05:42:55.234877Z","steps":["trace[1822581516] 'agreement among raft nodes before linearized reading' (duration: 1.349361668s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.234917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:53.885395Z","time spent":"1.349515921s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:42:55.235502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.622089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T05:42:55.235535Z","caller":"traceutil/trace.go:171","msg":"trace[1294432948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1689; }","duration":"134.68111ms","start":"2026-05-11T05:42:55.100847Z","end":"2026-05-11T05:42:55.235528Z","steps":["trace[1294432948] 'agreement among raft nodes before linearized reading' (duration: 134.619689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.235662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.659887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T05:42:55.235685Z","caller":"traceutil/trace.go:171","msg":"trace[68998314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1689; }","duration":"277.723369ms","start":"2026-05-11T05:42:54.957956Z","end":"2026-05-11T05:42:55.235679Z","steps":["trace[68998314] 'agreement among raft nodes before linearized reading' (duration: 277.656537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.235785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.066865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-11T05:42:55.235807Z","caller":"traceutil/trace.go:171","msg":"trace[1300067638] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1689; }","duration":"558.126227ms","start":"2026-05-11T05:42:54.677675Z","end":"2026-05-11T05:42:55.235801Z","steps":["trace[1300067638] 'agreement among raft nodes before linearized reading' (duration: 558.076986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.235823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:54.677657Z","time spent":"558.162077ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:42:55.235926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.16462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-11T05:42:55.235954Z","caller":"traceutil/trace.go:171","msg":"trace[1847410372] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1689; }","duration":"710.217202ms","start":"2026-05-11T05:42:54.525729Z","end":"2026-05-11T05:42:55.235946Z","steps":["trace[1847410372] 'agreement among raft nodes before linearized reading' (duration: 710.15996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.23598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:54.525715Z","time spent":"710.257942ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:42:55.236108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.700636ms","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-05-11T05:42:55.236142Z","caller":"traceutil/trace.go:171","msg":"trace[2086085909] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1689; }","duration":"834.773308ms","start":"2026-05-11T05:42:54.40136Z","end":"2026-05-11T05:42:55.236133Z","steps":["trace[2086085909] 'agreement among raft nodes before linearized reading' (duration: 834.715487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:55.236169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:54.401332Z","time spent":"834.82883ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:43:00.892087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:43:01.392277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:43:01.892451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:43:02.392803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:43:02.761769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.501777245s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T05:43:02.76215Z","caller":"traceutil/trace.go:171","msg":"trace[753384242] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"2.502259959s","start":"2026-05-11T05:43:00.259869Z","end":"2026-05-11T05:43:02.762129Z","steps":["trace[753384242] 'process raft request' (duration: 2.502134375s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:02.76225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.259855Z","time spent":"2.502330261s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:43:02.77316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000032301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-11T05:43:02.773225Z","caller":"traceutil/trace.go:171","msg":"trace[882596131] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000144335s","start":"2026-05-11T05:43:00.773067Z","end":"2026-05-11T05:43:02.773212Z","steps":["trace[882596131] 'agreement among raft nodes before linearized reading' (duration: 2.000067173s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:02.773255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.773047Z","time spent":"2.000201546s","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/11 05:43:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-11T05:43:03.57294Z","caller":"traceutil/trace.go:171","msg":"trace[1521461872] linearizableReadLoop","detail":"{readStateIndex:1785; appliedIndex:1781; }","duration":"3.181081689s","start":"2026-05-11T05:43:00.391831Z","end":"2026-05-11T05:43:03.572913Z","steps":["trace[1521461872] 'read index received' (duration: 2.370167575s)","trace[1521461872] 'applied index is now lower than readState.Index' (duration: 810.913014ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:43:03.573076Z","caller":"traceutil/trace.go:171","msg":"trace[748795980] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"3.312562156s","start":"2026-05-11T05:43:00.260501Z","end":"2026-05-11T05:43:03.573063Z","steps":["trace[748795980] 'process raft request' (duration: 3.312291159s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.260483Z","time spent":"3.312627527s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:03.573282Z","caller":"traceutil/trace.go:171","msg":"trace[1454000435] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"2.31790312s","start":"2026-05-11T05:43:01.255316Z","end":"2026-05-11T05:43:03.573219Z","steps":["trace[1454000435] 'process raft request' (duration: 2.317550541s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.57332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.18147372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:43:03.57336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.018988563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:43:03.573398Z","caller":"traceutil/trace.go:171","msg":"trace[872034320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1719; }","duration":"3.019071135s","start":"2026-05-11T05:43:00.554318Z","end":"2026-05-11T05:43:03.573389Z","steps":["trace[872034320] 'agreement among raft nodes before linearized reading' (duration: 3.019006794s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.294966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:43:03.5734Z","caller":"traceutil/trace.go:171","msg":"trace[997948770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1719; }","duration":"3.181613563s","start":"2026-05-11T05:43:00.391767Z","end":"2026-05-11T05:43:03.573381Z","steps":["trace[997948770] 'agreement among raft nodes before linearized reading' (duration: 3.181403278s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.554294Z","time spent":"3.019131266s","remote":"127.0.0.1:39788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:43:03.573441Z","caller":"traceutil/trace.go:171","msg":"trace[804708716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1719; }","duration":"800.377338ms","start":"2026-05-11T05:43:02.773055Z","end":"2026-05-11T05:43:03.573433Z","steps":["trace[804708716] 'agreement among raft nodes before linearized reading' (duration: 800.313197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:02.773037Z","time spent":"800.425329ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:43:03.573456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.391742Z","time spent":"3.181704736s","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:43:03.573494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.255292Z","time spent":"2.318093264s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:43:03.573598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2979979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-05-11T05:43:03.573637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.299008696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-11T05:43:03.573608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.887838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:43:03.573678Z","caller":"traceutil/trace.go:171","msg":"trace[1297004622] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1719; }","duration":"193.99354ms","start":"2026-05-11T05:43:03.379677Z","end":"2026-05-11T05:43:03.573671Z","steps":["trace[1297004622] 'agreement among raft nodes before linearized reading' (duration: 193.914298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.298446041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-11T05:43:03.573536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506458923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-11T05:43:03.57374Z","caller":"traceutil/trace.go:171","msg":"trace[940843496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1719; }","duration":"2.298482872s","start":"2026-05-11T05:43:01.275252Z","end":"2026-05-11T05:43:03.573735Z","steps":["trace[940843496] 'agreement among raft nodes before linearized reading' (duration: 2.29842089s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.297848655s","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":"warn","ts":"2026-05-11T05:43:03.573756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.275246Z","time spent":"2.298507072s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T05:43:03.573764Z","caller":"traceutil/trace.go:171","msg":"trace[503684842] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1719; }","duration":"1.506721559s","start":"2026-05-11T05:43:02.067029Z","end":"2026-05-11T05:43:03.573751Z","steps":["trace[503684842] 'agreement among raft nodes before linearized reading' (duration: 1.506451182s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:03.573768Z","caller":"traceutil/trace.go:171","msg":"trace[2033794709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1719; }","duration":"2.298186974s","start":"2026-05-11T05:43:01.27557Z","end":"2026-05-11T05:43:03.573757Z","steps":["trace[2033794709] 'agreement among raft nodes before linearized reading' (duration: 2.297782723s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.308277968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:43:03.573646Z","caller":"traceutil/trace.go:171","msg":"trace[1905284055] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1719; }","duration":"2.298058531s","start":"2026-05-11T05:43:01.275574Z","end":"2026-05-11T05:43:03.573633Z","steps":["trace[1905284055] 'agreement among raft nodes before linearized reading' (duration: 2.297979089s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:03.57381Z","caller":"traceutil/trace.go:171","msg":"trace[1904132199] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1719; }","duration":"2.308400761s","start":"2026-05-11T05:43:01.265402Z","end":"2026-05-11T05:43:03.573803Z","steps":["trace[1904132199] 'agreement among raft nodes before linearized reading' (duration: 2.308293639s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:02.067012Z","time spent":"1.50678399s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:43:03.573812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.275563Z","time spent":"2.298238336s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T05:43:03.573832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.26539Z","time spent":"2.308437152s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:43:03.573835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.275561Z","time spent":"2.298265277s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-11T05:43:03.573677Z","caller":"traceutil/trace.go:171","msg":"trace[1056539129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1719; }","duration":"2.299088018s","start":"2026-05-11T05:43:01.27458Z","end":"2026-05-11T05:43:03.573668Z","steps":["trace[1056539129] 'agreement among raft nodes before linearized reading' (duration: 2.298977905s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.573905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.274547Z","time spent":"2.299349885s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:43:03.574029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.29881892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T05:43:03.574063Z","caller":"traceutil/trace.go:171","msg":"trace[182586573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1719; }","duration":"2.298854021s","start":"2026-05-11T05:43:01.275201Z","end":"2026-05-11T05:43:03.574055Z","steps":["trace[182586573] 'agreement among raft nodes before linearized reading' (duration: 2.298698197s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.574083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.275175Z","time spent":"2.298904272s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T05:43:03.574158Z","caller":"traceutil/trace.go:171","msg":"trace[1054746254] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"3.313645564s","start":"2026-05-11T05:43:00.260475Z","end":"2026-05-11T05:43:03.574121Z","steps":["trace[1054746254] 'process raft request' (duration: 3.310902964s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.574164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.298972314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-11T05:43:03.574279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:00.260461Z","time spent":"3.313740067s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:03.574317Z","caller":"traceutil/trace.go:171","msg":"trace[1342014489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1719; }","duration":"2.2991628s","start":"2026-05-11T05:43:01.275132Z","end":"2026-05-11T05:43:03.574295Z","steps":["trace[1342014489] 'agreement among raft nodes before linearized reading' (duration: 2.298229305s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:03.574417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:01.275119Z","time spent":"2.299284202s","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T05:43:04.081763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878994134900980,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:43:04.170504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.578544Z","time spent":"591.952701ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-11T05:43:04.174457Z","caller":"traceutil/trace.go:171","msg":"trace[1205974124] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"595.829102ms","start":"2026-05-11T05:43:03.578599Z","end":"2026-05-11T05:43:04.174428Z","steps":["trace[1205974124] 'process raft request' (duration: 595.639718ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:04.174599Z","caller":"traceutil/trace.go:171","msg":"trace[278745066] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"595.878074ms","start":"2026-05-11T05:43:03.578708Z","end":"2026-05-11T05:43:04.174586Z","steps":["trace[278745066] 'process raft request' (duration: 595.665419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:04.174617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.578583Z","time spent":"595.953266ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:43:04.174713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.578701Z","time spent":"595.964416ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:04.174811Z","caller":"traceutil/trace.go:171","msg":"trace[67893911] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"596.10592ms","start":"2026-05-11T05:43:03.5787Z","end":"2026-05-11T05:43:04.174806Z","steps":["trace[67893911] 'process raft request' (duration: 595.846873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:04.174842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.578623Z","time spent":"596.204022ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:04.175061Z","caller":"traceutil/trace.go:171","msg":"trace[1198067207] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"595.541575ms","start":"2026-05-11T05:43:03.579493Z","end":"2026-05-11T05:43:04.175035Z","steps":["trace[1198067207] 'process raft request' (duration: 595.289368ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:04.175117Z","caller":"traceutil/trace.go:171","msg":"trace[1399359969] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"595.509964ms","start":"2026-05-11T05:43:03.5796Z","end":"2026-05-11T05:43:04.17511Z","steps":["trace[1399359969] 'process raft request' (duration: 595.359861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:04.175168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.579594Z","time spent":"595.542096ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:04.175216Z","caller":"traceutil/trace.go:171","msg":"trace[539025710] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"595.649568ms","start":"2026-05-11T05:43:03.579561Z","end":"2026-05-11T05:43:04.17521Z","steps":["trace[539025710] 'process raft request' (duration: 595.33468ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:04.175189Z","caller":"traceutil/trace.go:171","msg":"trace[1259598719] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"594.58243ms","start":"2026-05-11T05:43:03.58058Z","end":"2026-05-11T05:43:04.175163Z","steps":["trace[1259598719] 'process raft request' (duration: 594.429926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:04.175244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.579556Z","time spent":"595.674658ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:04.175297Z","caller":"traceutil/trace.go:171","msg":"trace[282756695] linearizableReadLoop","detail":"{readStateIndex:1793; appliedIndex:1785; }","duration":"594.007985ms","start":"2026-05-11T05:43:03.581284Z","end":"2026-05-11T05:43:04.175292Z","steps":["trace[282756695] 'read index received' (duration: 586.439927ms)","trace[282756695] 'applied index is now lower than readState.Index' (duration: 7.566178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:43:04.17532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.58057Z","time spent":"594.682682ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:43:04.175343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.058636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:43:04.175358Z","caller":"traceutil/trace.go:171","msg":"trace[448038815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1727; }","duration":"594.090946ms","start":"2026-05-11T05:43:03.581263Z","end":"2026-05-11T05:43:04.175354Z","steps":["trace[448038815] 'agreement among raft nodes before linearized reading' (duration: 594.048365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:04.175372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.581245Z","time spent":"594.124708ms","remote":"127.0.0.1:50718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:43:04.176666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:43:03.579477Z","time spent":"595.626037ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:43:20.58115Z","caller":"traceutil/trace.go:171","msg":"trace[1552993171] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"159.811773ms","start":"2026-05-11T05:43:20.421316Z","end":"2026-05-11T05:43:20.581127Z","steps":["trace[1552993171] 'process raft request' (duration: 159.71934ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:20.581197Z","caller":"traceutil/trace.go:171","msg":"trace[69939745] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"161.865126ms","start":"2026-05-11T05:43:20.419299Z","end":"2026-05-11T05:43:20.581164Z","steps":["trace[69939745] 'process raft request' (duration: 140.338153ms)","trace[69939745] 'compare' (duration: 21.303637ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:43:20.581537Z","caller":"traceutil/trace.go:171","msg":"trace[988199334] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"160.153201ms","start":"2026-05-11T05:43:20.421372Z","end":"2026-05-11T05:43:20.581525Z","steps":["trace[988199334] 'process raft request' (duration: 159.71956ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:22.73219Z","caller":"traceutil/trace.go:171","msg":"trace[1587877767] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"142.304336ms","start":"2026-05-11T05:43:22.58985Z","end":"2026-05-11T05:43:22.732154Z","steps":["trace[1587877767] 'process raft request' (duration: 142.102931ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:22.732401Z","caller":"traceutil/trace.go:171","msg":"trace[454469592] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"141.402992ms","start":"2026-05-11T05:43:22.590938Z","end":"2026-05-11T05:43:22.732341Z","steps":["trace[454469592] 'process raft request' (duration: 141.162665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:44:38.463186Z","caller":"traceutil/trace.go:171","msg":"trace[759151305] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"103.632738ms","start":"2026-05-11T05:44:38.359531Z","end":"2026-05-11T05:44:38.463163Z","steps":["trace[759151305] 'process raft request' (duration: 103.489055ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:44:38.898314Z","caller":"traceutil/trace.go:171","msg":"trace[1054572944] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"126.172587ms","start":"2026-05-11T05:44:38.772121Z","end":"2026-05-11T05:44:38.898294Z","steps":["trace[1054572944] 'read index received' (duration: 123.141408ms)","trace[1054572944] 'applied index is now lower than readState.Index' (duration: 3.030349ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:44:38.898416Z","caller":"traceutil/trace.go:171","msg":"trace[1327065528] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"128.242552ms","start":"2026-05-11T05:44:38.770158Z","end":"2026-05-11T05:44:38.898401Z","steps":["trace[1327065528] 'process raft request' (duration: 125.169951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:44:38.89859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.451015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:44:38.898676Z","caller":"traceutil/trace.go:171","msg":"trace[1418294538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"126.567487ms","start":"2026-05-11T05:44:38.772094Z","end":"2026-05-11T05:44:38.898661Z","steps":["trace[1418294538] 'agreement among raft nodes before linearized reading' (duration: 126.423714ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:10.228753Z","caller":"traceutil/trace.go:171","msg":"trace[314194756] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"153.380458ms","start":"2026-05-11T05:45:10.075348Z","end":"2026-05-11T05:45:10.228728Z","steps":["trace[314194756] 'process raft request' (duration: 152.204827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:10.674511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.300991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:45:10.674614Z","caller":"traceutil/trace.go:171","msg":"trace[702650109] linearizableReadLoop","detail":"{readStateIndex:2426; appliedIndex:2425; }","duration":"186.335989ms","start":"2026-05-11T05:45:10.488261Z","end":"2026-05-11T05:45:10.674597Z","steps":["trace[702650109] 'read index received' (duration: 28.211µs)","trace[702650109] 'applied index is now lower than readState.Index' (duration: 186.306768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:45:10.674708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.453821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:45:10.674752Z","caller":"traceutil/trace.go:171","msg":"trace[985253748] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2332; }","duration":"186.523113ms","start":"2026-05-11T05:45:10.488208Z","end":"2026-05-11T05:45:10.674731Z","steps":["trace[985253748] 'agreement among raft nodes before linearized reading' (duration: 186.469562ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:10.67487Z","caller":"traceutil/trace.go:171","msg":"trace[699616796] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"517.0462ms","start":"2026-05-11T05:45:10.15775Z","end":"2026-05-11T05:45:10.674796Z","steps":["trace[699616796] 'process raft request' (duration: 310.38841ms)","trace[699616796] 'compare' (duration: 206.198178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:45:10.675094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:45:10.157721Z","time spent":"517.248906ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:45:10.965559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.065055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T05:45:10.965723Z","caller":"traceutil/trace.go:171","msg":"trace[1494698366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2332; }","duration":"105.344093ms","start":"2026-05-11T05:45:10.860352Z","end":"2026-05-11T05:45:10.965696Z","steps":["trace[1494698366] 'range keys from in-memory index tree' (duration: 104.84117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:10.966045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.631771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:45:10.9663Z","caller":"traceutil/trace.go:171","msg":"trace[2089074134] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2332; }","duration":"119.137753ms","start":"2026-05-11T05:45:10.847013Z","end":"2026-05-11T05:45:10.966151Z","steps":["trace[2089074134] 'count revisions from in-memory index tree' (duration: 118.503126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:11.594591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.171463ms","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-05-11T05:45:11.594611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.354779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T05:45:11.594714Z","caller":"traceutil/trace.go:171","msg":"trace[1666296689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2335; }","duration":"194.424369ms","start":"2026-05-11T05:45:11.400267Z","end":"2026-05-11T05:45:11.594692Z","steps":["trace[1666296689] 'range keys from in-memory index tree' (duration: 193.903357ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:11.594725Z","caller":"traceutil/trace.go:171","msg":"trace[877070649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2335; }","duration":"160.519734ms","start":"2026-05-11T05:45:11.434184Z","end":"2026-05-11T05:45:11.594704Z","steps":["trace[877070649] 'range keys from in-memory index tree' (duration: 160.178845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:11.594501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.095454ms","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-05-11T05:45:11.595172Z","caller":"traceutil/trace.go:171","msg":"trace[664769363] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2335; }","duration":"160.844152ms","start":"2026-05-11T05:45:11.434262Z","end":"2026-05-11T05:45:11.595106Z","steps":["trace[664769363] 'range keys from in-memory index tree' (duration: 159.94422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:13.35111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.787813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T05:45:13.35119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.103816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:45:13.351376Z","caller":"traceutil/trace.go:171","msg":"trace[45455067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2344; }","duration":"256.044009ms","start":"2026-05-11T05:45:13.095254Z","end":"2026-05-11T05:45:13.351298Z","steps":["trace[45455067] 'range keys from in-memory index tree' (duration: 255.573178ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:13.351427Z","caller":"traceutil/trace.go:171","msg":"trace[929559771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2344; }","duration":"255.307541ms","start":"2026-05-11T05:45:13.096005Z","end":"2026-05-11T05:45:13.351313Z","steps":["trace[929559771] 'range keys from in-memory index tree' (duration: 254.910211ms)"],"step_count":1}