{"level":"warn","ts":"2026-03-02T02:06:51.797359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:06:51.797445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-03-02T02:06:51.797501Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:06:51.797514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-03-02T02:06:51.797534Z","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-03-02T02:06:51.798182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-03-02T02:06:51.798278Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-03-02T02:06:51.802756Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.280563ms"} {"level":"info","ts":"2026-03-02T02:06:51.81004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-03-02T02:06:51.810114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:06:51.810133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-03-02T02:06:51.810152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:06:51.810162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-03-02T02:06:51.810282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-03-02T02:06:51.818451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:06:51.819678Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:06:51.821928Z","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-03-02T02:06:51.82383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:06:51.82402Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:06:51.824289Z","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-03-02T02:06:51.824379Z","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-03-02T02:06:51.824421Z","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-03-02T02:06:51.825161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-03-02T02:06:51.825308Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-03-02T02:06:51.827632Z","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-03-02T02:06:51.827701Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-03-02T02:06:51.827781Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-03-02T02:06:51.827844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:06:51.827922Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:06:52.810701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:06:52.810776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:06:52.810801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-03-02T02:06:52.810815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:06:52.810822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-03-02T02:06:52.810833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-03-02T02:06:52.810842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-03-02T02:06:52.811598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:52.812407Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:06:52.812484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:06:52.81254Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:06:52.812538Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:52.812853Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:52.812886Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:52.813047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:06:52.813074Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:06:52.816315Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T02:06:52.826461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-03-02T02:06:58.295637Z","caller":"traceutil/trace.go:171","msg":"trace[38254997] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"160.162688ms","start":"2026-03-02T02:06:58.13544Z","end":"2026-03-02T02:06:58.295603Z","steps":["trace[38254997] 'process raft request' (duration: 159.960503ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:58.296552Z","caller":"traceutil/trace.go:171","msg":"trace[957614854] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:272; }","duration":"146.882289ms","start":"2026-03-02T02:06:58.149631Z","end":"2026-03-02T02:06:58.296513Z","steps":["trace[957614854] 'read index received' (duration: 145.762522ms)","trace[957614854] 'applied index is now lower than readState.Index' (duration: 1.118047ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:06:58.296627Z","caller":"traceutil/trace.go:171","msg":"trace[2089608345] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"153.373345ms","start":"2026-03-02T02:06:58.143228Z","end":"2026-03-02T02:06:58.296601Z","steps":["trace[2089608345] 'process raft request' (duration: 153.069328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:58.296946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.178276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-02T02:06:58.297057Z","caller":"traceutil/trace.go:171","msg":"trace[239607717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:266; }","duration":"147.444412ms","start":"2026-03-02T02:06:58.149594Z","end":"2026-03-02T02:06:58.297039Z","steps":["trace[239607717] 'agreement among raft nodes before linearized reading' (duration: 147.092744ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:02.808181Z","caller":"traceutil/trace.go:171","msg":"trace[1896303611] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"145.856953ms","start":"2026-03-02T02:07:02.662289Z","end":"2026-03-02T02:07:02.808146Z","steps":["trace[1896303611] 'process raft request' (duration: 145.544776ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:02.976639Z","caller":"traceutil/trace.go:171","msg":"trace[839712482] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"101.95844ms","start":"2026-03-02T02:07:02.874657Z","end":"2026-03-02T02:07:02.976616Z","steps":["trace[839712482] 'process raft request' (duration: 58.433064ms)","trace[839712482] 'compare' (duration: 43.423324ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:09.785343Z","caller":"traceutil/trace.go:171","msg":"trace[537345625] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"306.915304ms","start":"2026-03-02T02:07:09.478395Z","end":"2026-03-02T02:07:09.78531Z","steps":["trace[537345625] 'process raft request' (duration: 306.722329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:09.786174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:09.478373Z","time spent":"307.052176ms","remote":"127.0.0.1:33266","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-03-02T02:07:10.419787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:10.920932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:10.927919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.106638967s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:07:10.973001Z","caller":"traceutil/trace.go:171","msg":"trace[957992827] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"1.053581173s","start":"2026-03-02T02:07:09.919394Z","end":"2026-03-02T02:07:10.972976Z","steps":["trace[957992827] 'read index received' (duration: 1.008712835s)","trace[957992827] 'applied index is now lower than readState.Index' (duration: 44.867068ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:10.97314Z","caller":"traceutil/trace.go:171","msg":"trace[1506800954] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.173176115s","start":"2026-03-02T02:07:09.799949Z","end":"2026-03-02T02:07:10.973125Z","steps":["trace[1506800954] 'process raft request' (duration: 1.128273756s)","trace[1506800954] 'compare' (duration: 44.608622ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:10.973283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:09.799915Z","time spent":"1.173258217s","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:10.973306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.616588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T02:07:10.973411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053961341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T02:07:10.97344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.352954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:10.973517Z","caller":"traceutil/trace.go:171","msg":"trace[1077434087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"1.054132686s","start":"2026-03-02T02:07:09.919339Z","end":"2026-03-02T02:07:10.973472Z","steps":["trace[1077434087] 'agreement among raft nodes before linearized reading' (duration: 1.053870819s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:10.973402Z","caller":"traceutil/trace.go:171","msg":"trace[1821082150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"587.787382ms","start":"2026-03-02T02:07:10.385592Z","end":"2026-03-02T02:07:10.973379Z","steps":["trace[1821082150] 'agreement among raft nodes before linearized reading' (duration: 587.590418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:10.97358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.38556Z","time spent":"588.009307ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:10.973595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:09.919317Z","time spent":"1.054269199s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:07:10.973526Z","caller":"traceutil/trace.go:171","msg":"trace[130102241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"133.483467ms","start":"2026-03-02T02:07:10.840022Z","end":"2026-03-02T02:07:10.973505Z","steps":["trace[130102241] 'agreement among raft nodes before linearized reading' (duration: 133.230611ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:31.473184Z","caller":"traceutil/trace.go:171","msg":"trace[938002694] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"356.494374ms","start":"2026-03-02T02:07:31.116665Z","end":"2026-03-02T02:07:31.473159Z","steps":["trace[938002694] 'read index received' (duration: 356.396322ms)","trace[938002694] 'applied index is now lower than readState.Index' (duration: 96.692µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:31.473389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.712959ms","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-03-02T02:07:31.473451Z","caller":"traceutil/trace.go:171","msg":"trace[1945013437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"356.829042ms","start":"2026-03-02T02:07:31.116611Z","end":"2026-03-02T02:07:31.47344Z","steps":["trace[1945013437] 'agreement among raft nodes before linearized reading' (duration: 356.651128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:31.473483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:31.116586Z","time spent":"356.888704ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:07:31.47375Z","caller":"traceutil/trace.go:171","msg":"trace[1515056350] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"598.016497ms","start":"2026-03-02T02:07:30.875207Z","end":"2026-03-02T02:07:31.473223Z","steps":["trace[1515056350] 'process raft request' (duration: 597.796051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:31.473817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:30.875155Z","time spent":"598.619841ms","remote":"127.0.0.1:33176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:32.116784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.110172ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T02:07:32.117052Z","caller":"traceutil/trace.go:171","msg":"trace[1683220721] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"639.145385ms","start":"2026-03-02T02:07:31.47789Z","end":"2026-03-02T02:07:32.117035Z","steps":["trace[1683220721] 'process raft request' (duration: 639.027562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:32.117172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:31.477869Z","time spent":"639.229918ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:32.11722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.63152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-02T02:07:32.117342Z","caller":"traceutil/trace.go:171","msg":"trace[1882584024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"995.792283ms","start":"2026-03-02T02:07:31.121519Z","end":"2026-03-02T02:07:32.117311Z","steps":["trace[1882584024] 'agreement among raft nodes before linearized reading' (duration: 995.555377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:32.117394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:31.121509Z","time spent":"995.876985ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:32.117501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000527967s","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-03-02T02:07:32.11755Z","caller":"traceutil/trace.go:171","msg":"trace[988635888] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"644.268998ms","start":"2026-03-02T02:07:31.473264Z","end":"2026-03-02T02:07:32.117533Z","steps":["trace[988635888] 'read index received' (duration: 22.250904ms)","trace[988635888] 'applied index is now lower than readState.Index' (duration: 621.532763ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:32.117707Z","caller":"traceutil/trace.go:171","msg":"trace[1005676719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"1.000640639s","start":"2026-03-02T02:07:31.116931Z","end":"2026-03-02T02:07:32.117571Z","steps":["trace[1005676719] 'agreement among raft nodes before linearized reading' (duration: 1.000343802s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:32.117715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.48066ms","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-03-02T02:07:32.117777Z","caller":"traceutil/trace.go:171","msg":"trace[778089568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"186.617514ms","start":"2026-03-02T02:07:31.93114Z","end":"2026-03-02T02:07:32.117757Z","steps":["trace[778089568] 'agreement among raft nodes before linearized reading' (duration: 186.510911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:32.117768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:31.116924Z","time spent":"1.000831775s","remote":"127.0.0.1:33266","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-03-02T02:07:32.955681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.047588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:32.95581Z","caller":"traceutil/trace.go:171","msg":"trace[2013509975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"680.232493ms","start":"2026-03-02T02:07:32.275559Z","end":"2026-03-02T02:07:32.955792Z","steps":["trace[2013509975] 'range keys from in-memory index tree' (duration: 679.331812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:32.95591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:32.275542Z","time spent":"680.357286ms","remote":"127.0.0.1:32962","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-03-02T02:07:34.411269Z","caller":"traceutil/trace.go:171","msg":"trace[1497567586] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"236.669536ms","start":"2026-03-02T02:07:34.174564Z","end":"2026-03-02T02:07:34.411234Z","steps":["trace[1497567586] 'process raft request' (duration: 155.693361ms)","trace[1497567586] 'compare' (duration: 80.76619ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:34.411511Z","caller":"traceutil/trace.go:171","msg":"trace[1913868004] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"236.927572ms","start":"2026-03-02T02:07:34.174554Z","end":"2026-03-02T02:07:34.411481Z","steps":["trace[1913868004] 'process raft request' (duration: 236.625685ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:34.411741Z","caller":"traceutil/trace.go:171","msg":"trace[1417226835] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"237.054935ms","start":"2026-03-02T02:07:34.174677Z","end":"2026-03-02T02:07:34.411732Z","steps":["trace[1417226835] 'process raft request' (duration: 236.724757ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:35.373878Z","caller":"traceutil/trace.go:171","msg":"trace[1898652595] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"410.872671ms","start":"2026-03-02T02:07:34.962972Z","end":"2026-03-02T02:07:35.373845Z","steps":["trace[1898652595] 'process raft request' (duration: 410.666426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:35.374015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:34.962934Z","time spent":"411.019025ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:07:35.602617Z","caller":"traceutil/trace.go:171","msg":"trace[567431951] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"164.484341ms","start":"2026-03-02T02:07:35.438098Z","end":"2026-03-02T02:07:35.602582Z","steps":["trace[567431951] 'process raft request' (duration: 164.220325ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:35.619801Z","caller":"traceutil/trace.go:171","msg":"trace[1417703126] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"175.20718ms","start":"2026-03-02T02:07:35.444569Z","end":"2026-03-02T02:07:35.619777Z","steps":["trace[1417703126] 'process raft request' (duration: 175.071036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.253732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.888134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:40.254048Z","caller":"traceutil/trace.go:171","msg":"trace[167320192] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"333.257826ms","start":"2026-03-02T02:07:39.920737Z","end":"2026-03-02T02:07:40.253994Z","steps":["trace[167320192] 'process raft request' (duration: 144.939942ms)","trace[167320192] 'compare' (duration: 187.761191ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:40.254188Z","caller":"traceutil/trace.go:171","msg":"trace[1470494750] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"267.816974ms","start":"2026-03-02T02:07:39.986333Z","end":"2026-03-02T02:07:40.25415Z","steps":["trace[1470494750] 'process raft request' (duration: 267.507097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.254224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:39.920724Z","time spent":"333.37934ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:07:40.254417Z","caller":"traceutil/trace.go:171","msg":"trace[2130623654] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"179.7917ms","start":"2026-03-02T02:07:40.07461Z","end":"2026-03-02T02:07:40.254402Z","steps":["trace[2130623654] 'read index received' (duration: 176.88939ms)","trace[2130623654] 'applied index is now lower than readState.Index' (duration: 2.89971ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:40.254495Z","caller":"traceutil/trace.go:171","msg":"trace[1218484698] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"196.511581ms","start":"2026-03-02T02:07:40.057974Z","end":"2026-03-02T02:07:40.254486Z","steps":["trace[1218484698] 'process raft request' (duration: 196.135722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.254711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.068297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-02T02:07:40.254811Z","caller":"traceutil/trace.go:171","msg":"trace[319046796] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:573; }","duration":"180.200049ms","start":"2026-03-02T02:07:40.074586Z","end":"2026-03-02T02:07:40.254786Z","steps":["trace[319046796] 'agreement among raft nodes before linearized reading' (duration: 179.950823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.592825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.333307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:40.593046Z","caller":"traceutil/trace.go:171","msg":"trace[1884284072] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"334.615009ms","start":"2026-03-02T02:07:40.258396Z","end":"2026-03-02T02:07:40.593011Z","steps":["trace[1884284072] 'process raft request' (duration: 128.004876ms)","trace[1884284072] 'compare' (duration: 206.181873ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:40.593212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.258378Z","time spent":"334.747932ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:40.593459Z","caller":"traceutil/trace.go:171","msg":"trace[512226508] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"316.073423ms","start":"2026-03-02T02:07:40.277348Z","end":"2026-03-02T02:07:40.593422Z","steps":["trace[512226508] 'read index received' (duration: 109.08897ms)","trace[512226508] 'applied index is now lower than readState.Index' (duration: 206.980843ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:40.593713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.3495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:40.593756Z","caller":"traceutil/trace.go:171","msg":"trace[1077747601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"316.438622ms","start":"2026-03-02T02:07:40.277307Z","end":"2026-03-02T02:07:40.593746Z","steps":["trace[1077747601] 'agreement among raft nodes before linearized reading' (duration: 316.313749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.593796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.27729Z","time spent":"316.497484ms","remote":"127.0.0.1:32954","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-03-02T02:07:40.593903Z","caller":"traceutil/trace.go:171","msg":"trace[1303471650] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"290.435668ms","start":"2026-03-02T02:07:40.303437Z","end":"2026-03-02T02:07:40.593873Z","steps":["trace[1303471650] 'process raft request' (duration: 289.791272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.594399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.429598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:40.594503Z","caller":"traceutil/trace.go:171","msg":"trace[1376469799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"161.568712ms","start":"2026-03-02T02:07:40.43292Z","end":"2026-03-02T02:07:40.594489Z","steps":["trace[1376469799] 'agreement among raft nodes before linearized reading' (duration: 161.360527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.595744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.674016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T02:07:40.595765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.027498ms","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-03-02T02:07:40.595933Z","caller":"traceutil/trace.go:171","msg":"trace[1943002941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"154.871461ms","start":"2026-03-02T02:07:40.440997Z","end":"2026-03-02T02:07:40.595869Z","steps":["trace[1943002941] 'agreement among raft nodes before linearized reading' (duration: 154.540563ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:40.595943Z","caller":"traceutil/trace.go:171","msg":"trace[936878629] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"261.945343ms","start":"2026-03-02T02:07:40.333984Z","end":"2026-03-02T02:07:40.595929Z","steps":["trace[936878629] 'process raft request' (duration: 259.884424ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:40.595874Z","caller":"traceutil/trace.go:171","msg":"trace[1022794618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"156.174422ms","start":"2026-03-02T02:07:40.439686Z","end":"2026-03-02T02:07:40.59586Z","steps":["trace[1022794618] 'agreement among raft nodes before linearized reading' (duration: 156.008668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:40.594463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.804883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-02T02:07:40.597187Z","caller":"traceutil/trace.go:171","msg":"trace[1465670392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:576; }","duration":"123.539838ms","start":"2026-03-02T02:07:40.473624Z","end":"2026-03-02T02:07:40.597164Z","steps":["trace[1465670392] 'agreement among raft nodes before linearized reading' (duration: 120.73015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.106523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.508613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:41.106785Z","caller":"traceutil/trace.go:171","msg":"trace[1624248103] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"435.035931ms","start":"2026-03-02T02:07:40.671698Z","end":"2026-03-02T02:07:41.106734Z","steps":["trace[1624248103] 'process raft request' (duration: 71.244351ms)","trace[1624248103] 'compare' (duration: 363.385161ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:41.106804Z","caller":"traceutil/trace.go:171","msg":"trace[2077410329] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"432.631904ms","start":"2026-03-02T02:07:40.674143Z","end":"2026-03-02T02:07:41.106775Z","steps":["trace[2077410329] 'read index received' (duration: 68.968107ms)","trace[2077410329] 'applied index is now lower than readState.Index' (duration: 363.661507ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:41.106861Z","caller":"traceutil/trace.go:171","msg":"trace[309677215] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"410.79998ms","start":"2026-03-02T02:07:40.696045Z","end":"2026-03-02T02:07:41.106845Z","steps":["trace[309677215] 'process raft request' (duration: 410.611196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.106934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.696029Z","time spent":"410.867621ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:41.106945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.671675Z","time spent":"435.195446ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:07:41.107076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.911541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-02T02:07:41.107123Z","caller":"traceutil/trace.go:171","msg":"trace[1212235503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"433.004153ms","start":"2026-03-02T02:07:40.674105Z","end":"2026-03-02T02:07:41.107109Z","steps":["trace[1212235503] 'agreement among raft nodes before linearized reading' (duration: 432.820409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.107165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.67409Z","time spent":"433.066045ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-03-02T02:07:41.107276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.506985ms","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-03-02T02:07:41.107318Z","caller":"traceutil/trace.go:171","msg":"trace[1638132607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:581; }","duration":"418.580946ms","start":"2026-03-02T02:07:40.688726Z","end":"2026-03-02T02:07:41.107307Z","steps":["trace[1638132607] 'agreement among raft nodes before linearized reading' (duration: 418.469203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.107355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:40.688709Z","time spent":"418.637898ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:41.10743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.588391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-03-02T02:07:41.107515Z","caller":"traceutil/trace.go:171","msg":"trace[969549991] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"228.729286ms","start":"2026-03-02T02:07:40.878763Z","end":"2026-03-02T02:07:41.107493Z","steps":["trace[969549991] 'agreement among raft nodes before linearized reading' (duration: 228.529821ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.305054Z","caller":"traceutil/trace.go:171","msg":"trace[1368566316] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"190.526047ms","start":"2026-03-02T02:07:41.114493Z","end":"2026-03-02T02:07:41.305019Z","steps":["trace[1368566316] 'process raft request' (duration: 127.074193ms)","trace[1368566316] 'compare' (duration: 63.28553ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:41.448767Z","caller":"traceutil/trace.go:171","msg":"trace[846252804] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"171.719925ms","start":"2026-03-02T02:07:41.277023Z","end":"2026-03-02T02:07:41.448743Z","steps":["trace[846252804] 'read index received' (duration: 171.227683ms)","trace[846252804] 'applied index is now lower than readState.Index' (duration: 491.282µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:41.449073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.028633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-03-02T02:07:41.449052Z","caller":"traceutil/trace.go:171","msg":"trace[2126240559] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"271.924083ms","start":"2026-03-02T02:07:41.17709Z","end":"2026-03-02T02:07:41.449014Z","steps":["trace[2126240559] 'process raft request' (duration: 271.294948ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.449116Z","caller":"traceutil/trace.go:171","msg":"trace[885810207] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:584; }","duration":"172.159166ms","start":"2026-03-02T02:07:41.276945Z","end":"2026-03-02T02:07:41.449104Z","steps":["trace[885810207] 'agreement among raft nodes before linearized reading' (duration: 171.887779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.67991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.843985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-02T02:07:41.679981Z","caller":"traceutil/trace.go:171","msg":"trace[753717351] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:584; }","duration":"124.005469ms","start":"2026-03-02T02:07:41.555959Z","end":"2026-03-02T02:07:41.679965Z","steps":["trace[753717351] 'range keys from in-memory index tree' (duration: 123.714842ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.994633Z","caller":"traceutil/trace.go:171","msg":"trace[2126391361] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"248.971662ms","start":"2026-03-02T02:07:41.745642Z","end":"2026-03-02T02:07:41.994614Z","steps":["trace[2126391361] 'read index received' (duration: 248.787847ms)","trace[2126391361] 'applied index is now lower than readState.Index' (duration: 182.985µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:41.994741Z","caller":"traceutil/trace.go:171","msg":"trace[1127718663] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"253.931801ms","start":"2026-03-02T02:07:41.740781Z","end":"2026-03-02T02:07:41.994712Z","steps":["trace[1127718663] 'process raft request' (duration: 253.685405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.994826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.104905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.994905Z","caller":"traceutil/trace.go:171","msg":"trace[191476958] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:585; }","duration":"249.240728ms","start":"2026-03-02T02:07:41.745606Z","end":"2026-03-02T02:07:41.994847Z","steps":["trace[191476958] 'agreement among raft nodes before linearized reading' (duration: 249.110705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.99495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.463003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.995001Z","caller":"traceutil/trace.go:171","msg":"trace[1044071979] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:585; }","duration":"182.543736ms","start":"2026-03-02T02:07:41.812445Z","end":"2026-03-02T02:07:41.994988Z","steps":["trace[1044071979] 'agreement among raft nodes before linearized reading' (duration: 182.465234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.995031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.222857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.995146Z","caller":"traceutil/trace.go:171","msg":"trace[555798572] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:585; }","duration":"249.475324ms","start":"2026-03-02T02:07:41.745648Z","end":"2026-03-02T02:07:41.995124Z","steps":["trace[555798572] 'agreement among raft nodes before linearized reading' (duration: 249.157336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.995273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.169516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:07:41.995309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.560631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.995325Z","caller":"traceutil/trace.go:171","msg":"trace[1641640350] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:585; }","duration":"214.257147ms","start":"2026-03-02T02:07:41.781055Z","end":"2026-03-02T02:07:41.995313Z","steps":["trace[1641640350] 'agreement among raft nodes before linearized reading' (duration: 214.154885ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.995343Z","caller":"traceutil/trace.go:171","msg":"trace[1306765862] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:585; }","duration":"213.597092ms","start":"2026-03-02T02:07:41.781736Z","end":"2026-03-02T02:07:41.995333Z","steps":["trace[1306765862] 'agreement among raft nodes before linearized reading' (duration: 213.544761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.995413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.578621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:07:41.995428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.93005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.99544Z","caller":"traceutil/trace.go:171","msg":"trace[660665596] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:585; }","duration":"183.616861ms","start":"2026-03-02T02:07:41.811814Z","end":"2026-03-02T02:07:41.995431Z","steps":["trace[660665596] 'agreement among raft nodes before linearized reading' (duration: 183.514979ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.995453Z","caller":"traceutil/trace.go:171","msg":"trace[81833388] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:585; }","duration":"248.964542ms","start":"2026-03-02T02:07:41.746481Z","end":"2026-03-02T02:07:41.995445Z","steps":["trace[81833388] 'agreement among raft nodes before linearized reading' (duration: 248.927061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:41.995396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.289494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:07:41.99565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.604676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:41.995709Z","caller":"traceutil/trace.go:171","msg":"trace[267042524] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:585; }","duration":"214.679758ms","start":"2026-03-02T02:07:41.781023Z","end":"2026-03-02T02:07:41.995703Z","steps":["trace[267042524] 'agreement among raft nodes before linearized reading' (duration: 214.605796ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:41.995803Z","caller":"traceutil/trace.go:171","msg":"trace[2019683395] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:585; }","duration":"183.989681ms","start":"2026-03-02T02:07:41.811755Z","end":"2026-03-02T02:07:41.995744Z","steps":["trace[2019683395] 'agreement among raft nodes before linearized reading' (duration: 183.065919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.293266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.439205ms","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-03-02T02:07:42.293403Z","caller":"traceutil/trace.go:171","msg":"trace[829551595] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:585; }","duration":"175.589609ms","start":"2026-03-02T02:07:42.117794Z","end":"2026-03-02T02:07:42.293384Z","steps":["trace[829551595] 'range keys from in-memory index tree' (duration: 175.423815ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.294044Z","caller":"traceutil/trace.go:171","msg":"trace[1994631165] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"283.425258ms","start":"2026-03-02T02:07:42.010597Z","end":"2026-03-02T02:07:42.294022Z","steps":["trace[1994631165] 'process raft request' (duration: 221.260035ms)","trace[1994631165] 'compare' (duration: 61.455356ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:42.299504Z","caller":"traceutil/trace.go:171","msg":"trace[134081691] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"181.822638ms","start":"2026-03-02T02:07:42.117659Z","end":"2026-03-02T02:07:42.299481Z","steps":["trace[134081691] 'read index received' (duration: 114.214484ms)","trace[134081691] 'applied index is now lower than readState.Index' (duration: 67.606784ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:42.299544Z","caller":"traceutil/trace.go:171","msg":"trace[1949388094] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"263.038798ms","start":"2026-03-02T02:07:42.036477Z","end":"2026-03-02T02:07:42.299515Z","steps":["trace[1949388094] 'process raft request' (duration: 262.941076ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.299559Z","caller":"traceutil/trace.go:171","msg":"trace[564333043] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"264.532814ms","start":"2026-03-02T02:07:42.035001Z","end":"2026-03-02T02:07:42.299534Z","steps":["trace[564333043] 'process raft request' (duration: 264.301329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.299841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.551173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:07:42.299659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.985802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:42.299929Z","caller":"traceutil/trace.go:171","msg":"trace[1297565305] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:588; }","duration":"149.677976ms","start":"2026-03-02T02:07:42.150236Z","end":"2026-03-02T02:07:42.299914Z","steps":["trace[1297565305] 'agreement among raft nodes before linearized reading' (duration: 149.542952ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.299995Z","caller":"traceutil/trace.go:171","msg":"trace[593656660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"182.337911ms","start":"2026-03-02T02:07:42.117597Z","end":"2026-03-02T02:07:42.299935Z","steps":["trace[593656660] 'agreement among raft nodes before linearized reading' (duration: 181.995783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.300034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.971893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:42.300234Z","caller":"traceutil/trace.go:171","msg":"trace[53016679] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:588; }","duration":"150.23328ms","start":"2026-03-02T02:07:42.14998Z","end":"2026-03-02T02:07:42.300213Z","steps":["trace[53016679] 'agreement among raft nodes before linearized reading' (duration: 149.874551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.300662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.633959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:42.300793Z","caller":"traceutil/trace.go:171","msg":"trace[498012775] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:588; }","duration":"150.805913ms","start":"2026-03-02T02:07:42.149974Z","end":"2026-03-02T02:07:42.30078Z","steps":["trace[498012775] 'agreement among raft nodes before linearized reading' (duration: 149.778729ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:49.028035Z","caller":"traceutil/trace.go:171","msg":"trace[632299633] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"258.413379ms","start":"2026-03-02T02:07:48.769575Z","end":"2026-03-02T02:07:49.027989Z","steps":["trace[632299633] 'process raft request' (duration: 258.125422ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:49.028227Z","caller":"traceutil/trace.go:171","msg":"trace[593865480] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"258.494591ms","start":"2026-03-02T02:07:48.769706Z","end":"2026-03-02T02:07:49.0282Z","steps":["trace[593865480] 'process raft request' (duration: 258.222884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:53.327331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:07:53.409114Z","caller":"traceutil/trace.go:171","msg":"trace[2141966867] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"582.056421ms","start":"2026-03-02T02:07:52.827027Z","end":"2026-03-02T02:07:53.409084Z","steps":["trace[2141966867] 'read index received' (duration: 581.550539ms)","trace[2141966867] 'applied index is now lower than readState.Index' (duration: 504.792µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:53.409255Z","caller":"traceutil/trace.go:171","msg":"trace[1242385771] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"759.067339ms","start":"2026-03-02T02:07:52.650126Z","end":"2026-03-02T02:07:53.409193Z","steps":["trace[1242385771] 'process raft request' (duration: 758.533686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:53.409385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.336298ms","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-03-02T02:07:53.409429Z","caller":"traceutil/trace.go:171","msg":"trace[881626134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"582.4289ms","start":"2026-03-02T02:07:52.826986Z","end":"2026-03-02T02:07:53.409415Z","steps":["trace[881626134] 'agreement among raft nodes before linearized reading' (duration: 582.261406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:53.409429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.411898ms","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-03-02T02:07:53.409494Z","caller":"traceutil/trace.go:171","msg":"trace[1954641940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"365.530581ms","start":"2026-03-02T02:07:53.043946Z","end":"2026-03-02T02:07:53.409476Z","steps":["trace[1954641940] 'agreement among raft nodes before linearized reading' (duration: 365.341536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:53.409529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:53.043924Z","time spent":"365.597273ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:53.409549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:52.650101Z","time spent":"759.296494ms","remote":"127.0.0.1:33266","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-03-02T02:07:53.40956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.524191ms","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-03-02T02:07:53.409832Z","caller":"traceutil/trace.go:171","msg":"trace[379306773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"365.865129ms","start":"2026-03-02T02:07:53.043946Z","end":"2026-03-02T02:07:53.409811Z","steps":["trace[379306773] 'agreement among raft nodes before linearized reading' (duration: 365.397558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:53.409466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:52.826967Z","time spent":"582.490461ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:53.409876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:53.043919Z","time spent":"365.948401ms","remote":"127.0.0.1:33266","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-03-02T02:07:55.920651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861880,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:07:55.997438Z","caller":"traceutil/trace.go:171","msg":"trace[127369185] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"579.827508ms","start":"2026-03-02T02:07:55.417571Z","end":"2026-03-02T02:07:55.997398Z","steps":["trace[127369185] 'process raft request' (duration: 579.537812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:55.998026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.417525Z","time spent":"580.161626ms","remote":"127.0.0.1:33266","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-03-02T02:07:56.01304Z","caller":"traceutil/trace.go:171","msg":"trace[775047117] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:706; }","duration":"593.031313ms","start":"2026-03-02T02:07:55.419989Z","end":"2026-03-02T02:07:56.013021Z","steps":["trace[775047117] 'read index received' (duration: 577.151544ms)","trace[775047117] 'applied index is now lower than readState.Index' (duration: 15.878999ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:56.013128Z","caller":"traceutil/trace.go:171","msg":"trace[1508897215] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"570.400243ms","start":"2026-03-02T02:07:55.44272Z","end":"2026-03-02T02:07:56.01312Z","steps":["trace[1508897215] 'process raft request' (duration: 570.26618ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:56.013119Z","caller":"traceutil/trace.go:171","msg":"trace[1057230098] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"593.74244ms","start":"2026-03-02T02:07:55.419329Z","end":"2026-03-02T02:07:56.013072Z","steps":["trace[1057230098] 'process raft request' (duration: 593.563166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:56.013201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.442701Z","time spent":"570.445695ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:07:56.013342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.419311Z","time spent":"593.902033ms","remote":"127.0.0.1:33266","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-03-02T02:07:56.013598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.971099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T02:07:56.013629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.070406ms","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-03-02T02:07:56.013673Z","caller":"traceutil/trace.go:171","msg":"trace[649684554] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:683; }","duration":"120.115262ms","start":"2026-03-02T02:07:55.89354Z","end":"2026-03-02T02:07:56.013656Z","steps":["trace[649684554] 'agreement among raft nodes before linearized reading' (duration: 119.867386ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:56.013681Z","caller":"traceutil/trace.go:171","msg":"trace[1755219419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"591.159508ms","start":"2026-03-02T02:07:55.422507Z","end":"2026-03-02T02:07:56.013666Z","steps":["trace[1755219419] 'agreement among raft nodes before linearized reading' (duration: 590.919843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:56.013713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.422474Z","time spent":"591.23396ms","remote":"127.0.0.1:33266","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-03-02T02:07:56.013803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.355273ms","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-03-02T02:07:56.013897Z","caller":"traceutil/trace.go:171","msg":"trace[1656487354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"591.474775ms","start":"2026-03-02T02:07:55.422404Z","end":"2026-03-02T02:07:56.013879Z","steps":["trace[1656487354] 'agreement among raft nodes before linearized reading' (duration: 590.871671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:56.013935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.422397Z","time spent":"591.530558ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:56.015391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.356398ms","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-03-02T02:07:56.015514Z","caller":"traceutil/trace.go:171","msg":"trace[1242042059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"595.495452ms","start":"2026-03-02T02:07:55.419956Z","end":"2026-03-02T02:07:56.015451Z","steps":["trace[1242042059] 'agreement among raft nodes before linearized reading' (duration: 593.454743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:56.015604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:55.419944Z","time spent":"595.648395ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:56.779178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:57.279464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:57.779999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:58.279226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000137965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T02:07:58.279335Z","caller":"traceutil/trace.go:171","msg":"trace[1698891359] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000278728s","start":"2026-03-02T02:07:56.279009Z","end":"2026-03-02T02:07:58.279288Z","steps":["trace[1698891359] 'agreement among raft nodes before linearized reading' (duration: 2.000179766s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:58.279386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.278984Z","time spent":"2.000388471s","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 02:07:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T02:07:58.280297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:58.781121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:59.282173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:07:59.632111Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.519082424s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:07:59.680266Z","caller":"traceutil/trace.go:171","msg":"trace[1146401818] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"3.662221425s","start":"2026-03-02T02:07:56.01802Z","end":"2026-03-02T02:07:59.680241Z","steps":["trace[1146401818] 'process raft request' (duration: 3.614301493s)","trace[1146401818] 'compare' (duration: 47.152994ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:59.680326Z","caller":"traceutil/trace.go:171","msg":"trace[610212752] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"3.662212655s","start":"2026-03-02T02:07:56.018095Z","end":"2026-03-02T02:07:59.680308Z","steps":["trace[610212752] 'process raft request' (duration: 3.662077241s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:59.680379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.017999Z","time spent":"3.662323667s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:59.680451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.01809Z","time spent":"3.662295026s","remote":"127.0.0.1:33266","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-03-02T02:07:59.68049Z","caller":"traceutil/trace.go:171","msg":"trace[1720695916] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"3.660066733s","start":"2026-03-02T02:07:56.020399Z","end":"2026-03-02T02:07:59.680466Z","steps":["trace[1720695916] 'process raft request' (duration: 3.659878029s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:59.680618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.020391Z","time spent":"3.660155465s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:59.715468Z","caller":"traceutil/trace.go:171","msg":"trace[966269194] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"3.594728187s","start":"2026-03-02T02:07:56.120713Z","end":"2026-03-02T02:07:59.715442Z","steps":["trace[966269194] 'process raft request' (duration: 3.594381768s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:59.715532Z","caller":"traceutil/trace.go:171","msg":"trace[917825103] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:710; }","duration":"3.436442783s","start":"2026-03-02T02:07:56.279071Z","end":"2026-03-02T02:07:59.715514Z","steps":["trace[917825103] 'read index received' (duration: 3.353264511s)","trace[917825103] 'applied index is now lower than readState.Index' (duration: 83.177282ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:59.715667Z","caller":"traceutil/trace.go:171","msg":"trace[228273216] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"664.588768ms","start":"2026-03-02T02:07:59.05107Z","end":"2026-03-02T02:07:59.715658Z","steps":["trace[228273216] 'process raft request' (duration: 664.293801ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:59.715691Z","caller":"traceutil/trace.go:171","msg":"trace[974586592] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"3.520158639s","start":"2026-03-02T02:07:56.195225Z","end":"2026-03-02T02:07:59.715384Z","steps":["trace[974586592] 'process raft request' (duration: 3.520035526s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:59.715729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.120695Z","time spent":"3.59489414s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:59.715773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:59.051044Z","time spent":"664.64162ms","remote":"127.0.0.1:33266","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-03-02T02:07:59.715778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:56.195203Z","time spent":"3.520529149s","remote":"127.0.0.1:33016","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-03-02T02:08:00.215692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:00.279451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000070594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-02T02:08:00.279544Z","caller":"traceutil/trace.go:171","msg":"trace[1494068117] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000211167s","start":"2026-03-02T02:07:58.279302Z","end":"2026-03-02T02:08:00.279513Z","steps":["trace[1494068117] 'agreement among raft nodes before linearized reading' (duration: 2.000100304s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:00.279611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:58.279268Z","time spent":"2.00033187s","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:08:00.715913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:01.21682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:01.717737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:02.218505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:02.286604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000483114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T02:08:02.286691Z","caller":"traceutil/trace.go:171","msg":"trace[1267576706] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000636348s","start":"2026-03-02T02:08:00.286035Z","end":"2026-03-02T02:08:02.286672Z","steps":["trace[1267576706] 'agreement among raft nodes before linearized reading' (duration: 2.000530545s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:02.286743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.286007Z","time spent":"2.00072432s","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 02:08:02 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T02:08:02.696354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.815875ms","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-03-02T02:08:02.696444Z","caller":"traceutil/trace.go:171","msg":"trace[447273918] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:689; }","duration":"586.924067ms","start":"2026-03-02T02:08:02.109508Z","end":"2026-03-02T02:08:02.696432Z","steps":["trace[447273918] 'range keys from in-memory index tree' (duration: 586.804144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:02.696594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.171734Z","time spent":"524.856228ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-02T02:08:02.719078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748861895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:03.099935Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.384677391s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:08:03.100546Z","caller":"traceutil/trace.go:171","msg":"trace[2128570467] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"3.448949462s","start":"2026-03-02T02:07:59.651573Z","end":"2026-03-02T02:08:03.100523Z","steps":["trace[2128570467] 'process raft request' (duration: 3.448611844s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.10076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:59.65155Z","time spent":"3.449090446s","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:03.159292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.283172Z","time spent":"2.876114601s","remote":"127.0.0.1:33066","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T02:08:03.2074Z","caller":"traceutil/trace.go:171","msg":"trace[1283234440] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:716; }","duration":"3.491759582s","start":"2026-03-02T02:07:59.715613Z","end":"2026-03-02T02:08:03.207373Z","steps":["trace[1283234440] 'read index received' (duration: 3.384488746s)","trace[1283234440] 'applied index is now lower than readState.Index' (duration: 107.268116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:03.207497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.1893977s","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-03-02T02:08:03.207535Z","caller":"traceutil/trace.go:171","msg":"trace[1258899003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"5.189474821s","start":"2026-03-02T02:07:58.01805Z","end":"2026-03-02T02:08:03.207525Z","steps":["trace[1258899003] 'agreement among raft nodes before linearized reading' (duration: 5.189331798s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.207565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:58.018032Z","time spent":"5.189528273s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:03.207594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.489368755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-02T02:08:03.20763Z","caller":"traceutil/trace.go:171","msg":"trace[1987160436] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:691; }","duration":"3.489597741s","start":"2026-03-02T02:07:59.718021Z","end":"2026-03-02T02:08:03.207619Z","steps":["trace[1987160436] 'agreement among raft nodes before linearized reading' (duration: 3.489501209s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.207625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.223495539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.207659Z","caller":"traceutil/trace.go:171","msg":"trace[1071086799] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:691; }","duration":"2.223576641s","start":"2026-03-02T02:08:00.984073Z","end":"2026-03-02T02:08:03.20765Z","steps":["trace[1071086799] 'agreement among raft nodes before linearized reading' (duration: 2.223508349s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.20766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:59.717999Z","time spent":"3.489652412s","remote":"127.0.0.1:33150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-02T02:08:03.207687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.984056Z","time spent":"2.223622522s","remote":"127.0.0.1:54088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:03.2077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248095295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.207758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.663349971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.207785Z","caller":"traceutil/trace.go:171","msg":"trace[2036371298] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:691; }","duration":"2.663383772s","start":"2026-03-02T02:08:00.544393Z","end":"2026-03-02T02:08:03.207777Z","steps":["trace[2036371298] 'agreement among raft nodes before linearized reading' (duration: 2.663344421s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.207784Z","caller":"traceutil/trace.go:171","msg":"trace[253704267] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:691; }","duration":"2.248208298s","start":"2026-03-02T02:08:00.959551Z","end":"2026-03-02T02:08:03.207759Z","steps":["trace[253704267] 'agreement among raft nodes before linearized reading' (duration: 2.248074654s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.207806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.544389Z","time spent":"2.663411623s","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-03-02T02:08:03.207828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.959546Z","time spent":"2.248272659s","remote":"127.0.0.1:54078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-03-02T02:08:03.207853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.66367401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.207837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248571016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.207915Z","caller":"traceutil/trace.go:171","msg":"trace[576349748] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:691; }","duration":"2.663743831s","start":"2026-03-02T02:08:00.544158Z","end":"2026-03-02T02:08:03.207902Z","steps":["trace[576349748] 'agreement among raft nodes before linearized reading' (duration: 2.663665509s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.207928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.24870224s","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":"warn","ts":"2026-03-02T02:08:03.207946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.544154Z","time spent":"2.663782332s","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-03-02T02:08:03.20794Z","caller":"traceutil/trace.go:171","msg":"trace[175367923] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:691; }","duration":"2.24870943s","start":"2026-03-02T02:08:00.959214Z","end":"2026-03-02T02:08:03.207923Z","steps":["trace[175367923] 'agreement among raft nodes before linearized reading' (duration: 2.248572996s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.207988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.959195Z","time spent":"2.248782292s","remote":"127.0.0.1:54078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-02T02:08:03.208035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.664035018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.208042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.07966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.207951Z","caller":"traceutil/trace.go:171","msg":"trace[673670974] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:691; }","duration":"2.248750251s","start":"2026-03-02T02:08:00.959193Z","end":"2026-03-02T02:08:03.207943Z","steps":["trace[673670974] 'agreement among raft nodes before linearized reading' (duration: 2.24871657s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.208062Z","caller":"traceutil/trace.go:171","msg":"trace[1847795491] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:691; }","duration":"2.664091389s","start":"2026-03-02T02:08:00.543963Z","end":"2026-03-02T02:08:03.208054Z","steps":["trace[1847795491] 'agreement among raft nodes before linearized reading' (duration: 2.664044918s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.20808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.959178Z","time spent":"2.248892484s","remote":"127.0.0.1:54078","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":"warn","ts":"2026-03-02T02:08:03.208089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.543949Z","time spent":"2.6641323s","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"info","ts":"2026-03-02T02:08:03.208071Z","caller":"traceutil/trace.go:171","msg":"trace[196859371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"921.149682ms","start":"2026-03-02T02:08:02.286913Z","end":"2026-03-02T02:08:03.208063Z","steps":["trace[196859371] 'agreement among raft nodes before linearized reading' (duration: 921.105861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.224173545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.208165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.286891Z","time spent":"921.264975ms","remote":"127.0.0.1:32962","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-03-02T02:08:03.208183Z","caller":"traceutil/trace.go:171","msg":"trace[1176648489] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:691; }","duration":"2.224229837s","start":"2026-03-02T02:08:00.983944Z","end":"2026-03-02T02:08:03.208174Z","steps":["trace[1176648489] 'agreement among raft nodes before linearized reading' (duration: 2.224172955s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.983933Z","time spent":"2.224267317s","remote":"127.0.0.1:54088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-03-02T02:08:03.208313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.223766316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.20835Z","caller":"traceutil/trace.go:171","msg":"trace[2009135077] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:691; }","duration":"2.223817427s","start":"2026-03-02T02:08:00.984524Z","end":"2026-03-02T02:08:03.208341Z","steps":["trace[2009135077] 'agreement among raft nodes before linearized reading' (duration: 2.223760395s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82071045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.208379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.984515Z","time spent":"2.223855667s","remote":"127.0.0.1:54088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-03-02T02:08:03.208394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.237143005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.208441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110059234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.208445Z","caller":"traceutil/trace.go:171","msg":"trace[1919840740] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:691; }","duration":"2.237201756s","start":"2026-03-02T02:08:00.971233Z","end":"2026-03-02T02:08:03.208435Z","steps":["trace[1919840740] 'agreement among raft nodes before linearized reading' (duration: 2.237142024s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.208464Z","caller":"traceutil/trace.go:171","msg":"trace[758654233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"1.110122835s","start":"2026-03-02T02:08:02.098335Z","end":"2026-03-02T02:08:03.208458Z","steps":["trace[758654233] 'agreement among raft nodes before linearized reading' (duration: 1.110087334s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.971229Z","time spent":"2.237241797s","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:03.208503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.098314Z","time spent":"1.110169017s","remote":"127.0.0.1:32954","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-03-02T02:08:03.208318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.237089623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:03.208541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523898956s","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-03-02T02:08:03.208557Z","caller":"traceutil/trace.go:171","msg":"trace[1934031729] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:691; }","duration":"2.237346289s","start":"2026-03-02T02:08:00.9712Z","end":"2026-03-02T02:08:03.208546Z","steps":["trace[1934031729] 'agreement among raft nodes before linearized reading' (duration: 2.237087793s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.208574Z","caller":"traceutil/trace.go:171","msg":"trace[624838598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"1.523948788s","start":"2026-03-02T02:08:01.684618Z","end":"2026-03-02T02:08:03.208567Z","steps":["trace[624838598] 'agreement among raft nodes before linearized reading' (duration: 1.523854876s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:01.684611Z","time spent":"1.523981139s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:03.208599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.971192Z","time spent":"2.23739485s","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-02T02:08:03.208358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.23611425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.208631Z","caller":"traceutil/trace.go:171","msg":"trace[459910108] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:691; }","duration":"2.236423897s","start":"2026-03-02T02:08:00.972197Z","end":"2026-03-02T02:08:03.208621Z","steps":["trace[459910108] 'agreement among raft nodes before linearized reading' (duration: 2.23614193s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:00.972181Z","time spent":"2.236480958s","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-02T02:08:03.208688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525011513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:08:03.20872Z","caller":"traceutil/trace.go:171","msg":"trace[1612573351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"1.525084644s","start":"2026-03-02T02:08:01.683627Z","end":"2026-03-02T02:08:03.208711Z","steps":["trace[1612573351] 'agreement among raft nodes before linearized reading' (duration: 1.524961171s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.20839Z","caller":"traceutil/trace.go:171","msg":"trace[1511263274] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:691; }","duration":"1.820797762s","start":"2026-03-02T02:08:01.387586Z","end":"2026-03-02T02:08:03.208383Z","steps":["trace[1511263274] 'agreement among raft nodes before linearized reading' (duration: 1.820685519s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523233711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T02:08:03.208754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:01.683605Z","time spent":"1.525141206s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:08:03.208761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:01.387571Z","time spent":"1.821179891s","remote":"127.0.0.1:33430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-03-02T02:08:03.208766Z","caller":"traceutil/trace.go:171","msg":"trace[561292616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"1.523288242s","start":"2026-03-02T02:08:01.685471Z","end":"2026-03-02T02:08:03.208759Z","steps":["trace[561292616] 'agreement among raft nodes before linearized reading' (duration: 1.523224841s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.208862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:01.685454Z","time spent":"1.523398885s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:08:03.209052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.206058466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:03.209107Z","caller":"traceutil/trace.go:171","msg":"trace[1593246184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"5.20617303s","start":"2026-03-02T02:07:58.002927Z","end":"2026-03-02T02:08:03.2091Z","steps":["trace[1593246184] 'agreement among raft nodes before linearized reading' (duration: 5.20452984s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.209129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:58.002875Z","time spent":"5.206249361s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:08:03.625353Z","caller":"traceutil/trace.go:171","msg":"trace[493065422] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"410.076831ms","start":"2026-03-02T02:08:03.215193Z","end":"2026-03-02T02:08:03.62527Z","steps":["trace[493065422] 'process raft request' (duration: 409.848496ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.625499Z","caller":"traceutil/trace.go:171","msg":"trace[1083121422] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:720; }","duration":"408.897194ms","start":"2026-03-02T02:08:03.216595Z","end":"2026-03-02T02:08:03.625492Z","steps":["trace[1083121422] 'read index received' (duration: 7.814987ms)","trace[1083121422] 'applied index is now lower than readState.Index' (duration: 401.081617ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:03.62556Z","caller":"traceutil/trace.go:171","msg":"trace[1376599240] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"409.59839ms","start":"2026-03-02T02:08:03.215957Z","end":"2026-03-02T02:08:03.625555Z","steps":["trace[1376599240] 'process raft request' (duration: 409.401815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.625588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.215159Z","time spent":"410.278386ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:03.625633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.215944Z","time spent":"409.633851ms","remote":"127.0.0.1:33266","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-03-02T02:08:03.625811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.465301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:03.625897Z","caller":"traceutil/trace.go:171","msg":"trace[2018733105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"405.585185ms","start":"2026-03-02T02:08:03.220289Z","end":"2026-03-02T02:08:03.625875Z","steps":["trace[2018733105] 'agreement among raft nodes before linearized reading' (duration: 405.444461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.625942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.220279Z","time spent":"405.654996ms","remote":"127.0.0.1:32954","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-03-02T02:08:03.62604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.447976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1898e42030e039cb\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-02T02:08:03.626078Z","caller":"traceutil/trace.go:171","msg":"trace[358284826] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1898e42030e039cb; range_end:; response_count:1; response_revision:696; }","duration":"409.510688ms","start":"2026-03-02T02:08:03.216557Z","end":"2026-03-02T02:08:03.626068Z","steps":["trace[358284826] 'agreement among raft nodes before linearized reading' (duration: 409.396876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.626105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.216546Z","time spent":"409.553689ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1898e42030e039cb\" "} {"level":"info","ts":"2026-03-02T02:08:03.625398Z","caller":"traceutil/trace.go:171","msg":"trace[1587099719] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"410.071121ms","start":"2026-03-02T02:08:03.215319Z","end":"2026-03-02T02:08:03.62539Z","steps":["trace[1587099719] 'process raft request' (duration: 409.872096ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.62528Z","caller":"traceutil/trace.go:171","msg":"trace[1019233772] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"409.928238ms","start":"2026-03-02T02:08:03.215296Z","end":"2026-03-02T02:08:03.625225Z","steps":["trace[1019233772] 'process raft request' (duration: 409.868087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.626331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.215264Z","time spent":"411.039724ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:03.626329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:03.215305Z","time spent":"410.918121ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:03.796269Z","caller":"traceutil/trace.go:171","msg":"trace[369633139] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"105.493064ms","start":"2026-03-02T02:08:03.690749Z","end":"2026-03-02T02:08:03.796242Z","steps":["trace[369633139] 'read index received' (duration: 85.791714ms)","trace[369633139] 'applied index is now lower than readState.Index' (duration: 19.70022ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:03.796302Z","caller":"traceutil/trace.go:171","msg":"trace[703286622] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"162.536843ms","start":"2026-03-02T02:08:03.633724Z","end":"2026-03-02T02:08:03.796261Z","steps":["trace[703286622] 'process raft request' (duration: 142.920966ms)","trace[703286622] 'compare' (duration: 19.479554ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:03.79642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.650548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:08:03.796464Z","caller":"traceutil/trace.go:171","msg":"trace[1199559664] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:697; }","duration":"105.776511ms","start":"2026-03-02T02:08:03.690679Z","end":"2026-03-02T02:08:03.796455Z","steps":["trace[1199559664] 'agreement among raft nodes before linearized reading' (duration: 105.652058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:04.927733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.338224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:04.927821Z","caller":"traceutil/trace.go:171","msg":"trace[2012443754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"649.477687ms","start":"2026-03-02T02:08:04.278325Z","end":"2026-03-02T02:08:04.927803Z","steps":["trace[2012443754] 'range keys from in-memory index tree' (duration: 649.221402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:04.927855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:04.278299Z","time spent":"649.54847ms","remote":"127.0.0.1:32954","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-03-02T02:08:05.340978Z","caller":"traceutil/trace.go:171","msg":"trace[2100120491] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"103.299481ms","start":"2026-03-02T02:08:05.237642Z","end":"2026-03-02T02:08:05.340941Z","steps":["trace[2100120491] 'process raft request' (duration: 103.077306ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:08.240785Z","caller":"traceutil/trace.go:171","msg":"trace[899487514] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"118.261108ms","start":"2026-03-02T02:08:08.1225Z","end":"2026-03-02T02:08:08.240761Z","steps":["trace[899487514] 'process raft request' (duration: 118.036883ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:20.113255Z","caller":"traceutil/trace.go:171","msg":"trace[393878308] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"176.195569ms","start":"2026-03-02T02:08:19.937035Z","end":"2026-03-02T02:08:20.113231Z","steps":["trace[393878308] 'process raft request' (duration: 176.150757ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:20.113797Z","caller":"traceutil/trace.go:171","msg":"trace[2022825588] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"177.756156ms","start":"2026-03-02T02:08:19.936013Z","end":"2026-03-02T02:08:20.113769Z","steps":["trace[2022825588] 'process raft request' (duration: 177.040209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:21.512857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.924908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T02:08:21.513024Z","caller":"traceutil/trace.go:171","msg":"trace[1424474333] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"235.297976ms","start":"2026-03-02T02:08:21.277642Z","end":"2026-03-02T02:08:21.51294Z","steps":["trace[1424474333] 'read index received' (duration: 5.169013ms)","trace[1424474333] 'applied index is now lower than readState.Index' (duration: 230.128023ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:21.513108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.46529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:21.513146Z","caller":"traceutil/trace.go:171","msg":"trace[264734678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"235.507091ms","start":"2026-03-02T02:08:21.277627Z","end":"2026-03-02T02:08:21.513134Z","steps":["trace[264734678] 'agreement among raft nodes before linearized reading' (duration: 235.439609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:21.513152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.850083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:08:21.513327Z","caller":"traceutil/trace.go:171","msg":"trace[1615105697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"104.064878ms","start":"2026-03-02T02:08:21.409254Z","end":"2026-03-02T02:08:21.513319Z","steps":["trace[1615105697] 'agreement among raft nodes before linearized reading' (duration: 103.828373ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:22.107964Z","caller":"traceutil/trace.go:171","msg":"trace[1430798659] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"163.23595ms","start":"2026-03-02T02:08:21.944701Z","end":"2026-03-02T02:08:22.107937Z","steps":["trace[1430798659] 'read index received' (duration: 162.959804ms)","trace[1430798659] 'applied index is now lower than readState.Index' (duration: 275.226µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:22.108208Z","caller":"traceutil/trace.go:171","msg":"trace[1769738362] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"219.169933ms","start":"2026-03-02T02:08:21.888857Z","end":"2026-03-02T02:08:22.108027Z","steps":["trace[1769738362] 'process raft request' (duration: 218.865146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:22.108514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.800084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:22.108652Z","caller":"traceutil/trace.go:171","msg":"trace[1917528609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"164.031369ms","start":"2026-03-02T02:08:21.944604Z","end":"2026-03-02T02:08:22.108635Z","steps":["trace[1917528609] 'agreement among raft nodes before linearized reading' (duration: 163.458385ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:23.733909Z","caller":"traceutil/trace.go:171","msg":"trace[814898537] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"175.000661ms","start":"2026-03-02T02:08:23.558886Z","end":"2026-03-02T02:08:23.733887Z","steps":["trace[814898537] 'process raft request' (duration: 174.878698ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:30.420224Z","caller":"traceutil/trace.go:171","msg":"trace[1269350203] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"117.106441ms","start":"2026-03-02T02:08:30.303084Z","end":"2026-03-02T02:08:30.42019Z","steps":["trace[1269350203] 'process raft request' (duration: 116.997628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.396473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:36.897026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:36.947255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.138902181s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:08:36.94773Z","caller":"traceutil/trace.go:171","msg":"trace[1665414882] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"1.051435128s","start":"2026-03-02T02:08:35.896268Z","end":"2026-03-02T02:08:36.947703Z","steps":["trace[1665414882] 'read index received' (duration: 1.051231793s)","trace[1665414882] 'applied index is now lower than readState.Index' (duration: 201.465µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:36.947819Z","caller":"traceutil/trace.go:171","msg":"trace[1708937455] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"1.139566997s","start":"2026-03-02T02:08:35.808206Z","end":"2026-03-02T02:08:36.947773Z","steps":["trace[1708937455] 'process raft request' (duration: 1.139318761s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.003356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-02T02:08:36.948071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:35.808172Z","time spent":"1.139747872s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:36.948142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.786036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-02T02:08:36.948106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.726267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:36.948161Z","caller":"traceutil/trace.go:171","msg":"trace[985128467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"490.831407ms","start":"2026-03-02T02:08:36.457325Z","end":"2026-03-02T02:08:36.948157Z","steps":["trace[985128467] 'agreement among raft nodes before linearized reading' (duration: 490.782866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.037165ms","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-03-02T02:08:36.948186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.457312Z","time spent":"490.869158ms","remote":"127.0.0.1:33266","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-03-02T02:08:36.948192Z","caller":"traceutil/trace.go:171","msg":"trace[1865581291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"677.147097ms","start":"2026-03-02T02:08:36.271032Z","end":"2026-03-02T02:08:36.948179Z","steps":["trace[1865581291] 'agreement among raft nodes before linearized reading' (duration: 677.061315ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:36.948197Z","caller":"traceutil/trace.go:171","msg":"trace[729825574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"668.85929ms","start":"2026-03-02T02:08:36.279314Z","end":"2026-03-02T02:08:36.948174Z","steps":["trace[729825574] 'agreement among raft nodes before linearized reading' (duration: 668.729827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.270985Z","time spent":"677.22797ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:36.948247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.279293Z","time spent":"668.945572ms","remote":"127.0.0.1:32954","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-03-02T02:08:36.948095Z","caller":"traceutil/trace.go:171","msg":"trace[494030628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"696.12436ms","start":"2026-03-02T02:08:36.251961Z","end":"2026-03-02T02:08:36.948085Z","steps":["trace[494030628] 'agreement among raft nodes before linearized reading' (duration: 695.989876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.251923Z","time spent":"696.366886ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:08:36.948266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.656614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-02T02:08:36.948055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051762635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T02:08:36.948338Z","caller":"traceutil/trace.go:171","msg":"trace[389991695] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:840; }","duration":"1.052126724s","start":"2026-03-02T02:08:35.896205Z","end":"2026-03-02T02:08:36.948332Z","steps":["trace[389991695] 'agreement among raft nodes before linearized reading' (duration: 1.051716054s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:35.896174Z","time spent":"1.052175615s","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-03-02T02:08:36.948354Z","caller":"traceutil/trace.go:171","msg":"trace[2021341741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"493.819409ms","start":"2026-03-02T02:08:36.454515Z","end":"2026-03-02T02:08:36.948335Z","steps":["trace[2021341741] 'agreement among raft nodes before linearized reading' (duration: 493.522782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:36.948425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.454485Z","time spent":"493.931701ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:37.839054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.949618Z","time spent":"889.429937ms","remote":"127.0.0.1:33016","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T02:08:37.8903Z","caller":"traceutil/trace.go:171","msg":"trace[1856932944] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"904.576508ms","start":"2026-03-02T02:08:36.985707Z","end":"2026-03-02T02:08:37.890284Z","steps":["trace[1856932944] 'process raft request' (duration: 904.429964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:37.890394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.985692Z","time spent":"904.647009ms","remote":"127.0.0.1:33266","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-03-02T02:08:37.890642Z","caller":"traceutil/trace.go:171","msg":"trace[1750709194] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"904.165577ms","start":"2026-03-02T02:08:36.986436Z","end":"2026-03-02T02:08:37.890601Z","steps":["trace[1750709194] 'process raft request' (duration: 903.821059ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:37.890697Z","caller":"traceutil/trace.go:171","msg":"trace[1485585775] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"904.183159ms","start":"2026-03-02T02:08:36.986506Z","end":"2026-03-02T02:08:37.890689Z","steps":["trace[1485585775] 'process raft request' (duration: 903.995274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:37.890741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.986502Z","time spent":"904.215429ms","remote":"127.0.0.1:33266","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-03-02T02:08:37.890789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.986416Z","time spent":"904.298561ms","remote":"127.0.0.1:33266","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-03-02T02:08:37.891341Z","caller":"traceutil/trace.go:171","msg":"trace[112756617] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"904.794783ms","start":"2026-03-02T02:08:36.986535Z","end":"2026-03-02T02:08:37.89133Z","steps":["trace[112756617] 'process raft request' (duration: 904.123117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:37.891383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:36.986531Z","time spent":"904.827233ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:50.113972Z","caller":"traceutil/trace.go:171","msg":"trace[1577352986] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"116.886885ms","start":"2026-03-02T02:08:49.997064Z","end":"2026-03-02T02:08:50.113951Z","steps":["trace[1577352986] 'read index received' (duration: 116.67317ms)","trace[1577352986] 'applied index is now lower than readState.Index' (duration: 212.905µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:50.113994Z","caller":"traceutil/trace.go:171","msg":"trace[2109315051] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"140.998939ms","start":"2026-03-02T02:08:49.972974Z","end":"2026-03-02T02:08:50.113973Z","steps":["trace[2109315051] 'process raft request' (duration: 140.850406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:50.114129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.048278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:50.114161Z","caller":"traceutil/trace.go:171","msg":"trace[922192311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"117.108529ms","start":"2026-03-02T02:08:49.997041Z","end":"2026-03-02T02:08:50.11415Z","steps":["trace[922192311] 'agreement among raft nodes before linearized reading' (duration: 116.996837ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:50.22599Z","caller":"traceutil/trace.go:171","msg":"trace[546236253] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"104.453199ms","start":"2026-03-02T02:08:50.121515Z","end":"2026-03-02T02:08:50.225968Z","steps":["trace[546236253] 'process raft request' (duration: 60.82289ms)","trace[546236253] 'compare' (duration: 43.472266ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:50.226294Z","caller":"traceutil/trace.go:171","msg":"trace[185149128] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"104.318426ms","start":"2026-03-02T02:08:50.121943Z","end":"2026-03-02T02:08:50.226262Z","steps":["trace[185149128] 'process raft request' (duration: 103.970848ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:50.226387Z","caller":"traceutil/trace.go:171","msg":"trace[657928988] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"102.958854ms","start":"2026-03-02T02:08:50.123419Z","end":"2026-03-02T02:08:50.226378Z","steps":["trace[657928988] 'process raft request' (duration: 102.745539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:53.891117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862413,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:08:53.98239Z","caller":"traceutil/trace.go:171","msg":"trace[214496005] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"591.618405ms","start":"2026-03-02T02:08:53.390749Z","end":"2026-03-02T02:08:53.982367Z","steps":["trace[214496005] 'read index received' (duration: 591.37431ms)","trace[214496005] 'applied index is now lower than readState.Index' (duration: 243.065µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:53.98242Z","caller":"traceutil/trace.go:171","msg":"trace[1896323244] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"657.931749ms","start":"2026-03-02T02:08:53.324464Z","end":"2026-03-02T02:08:53.982396Z","steps":["trace[1896323244] 'process raft request' (duration: 657.762855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:53.982547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:53.324449Z","time spent":"658.02796ms","remote":"127.0.0.1:33176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:53.982547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.798609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:08:53.982595Z","caller":"traceutil/trace.go:171","msg":"trace[1984886391] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:903; }","duration":"591.884681ms","start":"2026-03-02T02:08:53.390701Z","end":"2026-03-02T02:08:53.982585Z","steps":["trace[1984886391] 'agreement among raft nodes before linearized reading' (duration: 591.769838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:53.98262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:53.39068Z","time spent":"591.935502ms","remote":"127.0.0.1:33374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:54.62503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:55.119146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.132080944s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:08:55.133721Z","caller":"traceutil/trace.go:171","msg":"trace[2115254921] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.146689684s","start":"2026-03-02T02:08:53.986991Z","end":"2026-03-02T02:08:55.13368Z","steps":["trace[2115254921] 'process raft request' (duration: 1.132343732s)","trace[2115254921] 'compare' (duration: 14.054561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:55.133879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:53.986974Z","time spent":"1.146837296s","remote":"127.0.0.1:33150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:55.384128Z","caller":"traceutil/trace.go:171","msg":"trace[1295897814] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"1.259613682s","start":"2026-03-02T02:08:54.124498Z","end":"2026-03-02T02:08:55.384112Z","steps":["trace[1295897814] 'read index received' (duration: 994.847523ms)","trace[1295897814] 'applied index is now lower than readState.Index' (duration: 264.765459ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:55.38417Z","caller":"traceutil/trace.go:171","msg":"trace[1818466910] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"1.392085602s","start":"2026-03-02T02:08:53.992055Z","end":"2026-03-02T02:08:55.384141Z","steps":["trace[1818466910] 'process raft request' (duration: 1.392019407s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:55.38421Z","caller":"traceutil/trace.go:171","msg":"trace[2064986864] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.39591295s","start":"2026-03-02T02:08:53.988267Z","end":"2026-03-02T02:08:55.38418Z","steps":["trace[2064986864] 'process raft request' (duration: 1.341370848s)","trace[2064986864] 'compare' (duration: 54.333995ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:55.384315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145661437s","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-03-02T02:08:55.384316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25980777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-02T02:08:55.384312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:53.992039Z","time spent":"1.392198746s","remote":"127.0.0.1:33510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:55.384346Z","caller":"traceutil/trace.go:171","msg":"trace[734797969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"1.145730385s","start":"2026-03-02T02:08:54.238609Z","end":"2026-03-02T02:08:55.384339Z","steps":["trace[734797969] 'agreement among raft nodes before linearized reading' (duration: 1.145615533s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:55.384346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:53.988239Z","time spent":"1.396035164s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:55.384369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:54.238592Z","time spent":"1.145771484s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:08:55.384362Z","caller":"traceutil/trace.go:171","msg":"trace[1431066452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"1.259891037s","start":"2026-03-02T02:08:54.124458Z","end":"2026-03-02T02:08:55.384349Z","steps":["trace[1431066452] 'agreement among raft nodes before linearized reading' (duration: 1.259759015s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:55.384404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:54.124439Z","time spent":"1.259957444s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:08:55.38455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143158773s","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-03-02T02:08:55.384572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145913529s","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-03-02T02:08:55.384591Z","caller":"traceutil/trace.go:171","msg":"trace[315312892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"1.145940298s","start":"2026-03-02T02:08:54.238646Z","end":"2026-03-02T02:08:55.384586Z","steps":["trace[315312892] 'agreement among raft nodes before linearized reading' (duration: 1.145885392s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:55.384604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.829302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:55.384617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:54.238643Z","time spent":"1.145968076s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:55.384633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.216689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:55.384638Z","caller":"traceutil/trace.go:171","msg":"trace[470715539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"104.885228ms","start":"2026-03-02T02:08:55.279742Z","end":"2026-03-02T02:08:55.384627Z","steps":["trace[470715539] 'agreement among raft nodes before linearized reading' (duration: 104.844621ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:55.384589Z","caller":"traceutil/trace.go:171","msg":"trace[1040797438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"1.14320556s","start":"2026-03-02T02:08:54.241375Z","end":"2026-03-02T02:08:55.384581Z","steps":["trace[1040797438] 'agreement among raft nodes before linearized reading' (duration: 1.143117316s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:55.384672Z","caller":"traceutil/trace.go:171","msg":"trace[1926622559] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:906; }","duration":"315.292214ms","start":"2026-03-02T02:08:55.069369Z","end":"2026-03-02T02:08:55.384661Z","steps":["trace[1926622559] 'agreement among raft nodes before linearized reading' (duration: 315.240198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:55.384706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:54.24137Z","time spent":"1.143326623s","remote":"127.0.0.1:33266","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-03-02T02:08:55.384708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:55.069345Z","time spent":"315.3529ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:55.384551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.568996ms","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-03-02T02:08:55.384813Z","caller":"traceutil/trace.go:171","msg":"trace[1575095132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"280.856587ms","start":"2026-03-02T02:08:55.103945Z","end":"2026-03-02T02:08:55.384801Z","steps":["trace[1575095132] 'agreement among raft nodes before linearized reading' (duration: 280.493051ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:57.230832Z","caller":"traceutil/trace.go:171","msg":"trace[1794778027] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"247.006312ms","start":"2026-03-02T02:08:56.98379Z","end":"2026-03-02T02:08:57.230797Z","steps":["trace[1794778027] 'process raft request' (duration: 246.823827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.940366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.655888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-02T02:08:57.940455Z","caller":"traceutil/trace.go:171","msg":"trace[736274492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"460.779142ms","start":"2026-03-02T02:08:57.479656Z","end":"2026-03-02T02:08:57.940435Z","steps":["trace[736274492] 'range keys from in-memory index tree' (duration: 460.523895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.940499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.479649Z","time spent":"460.841053ms","remote":"127.0.0.1:33266","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-03-02T02:08:57.940739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.622852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:57.940792Z","caller":"traceutil/trace.go:171","msg":"trace[499742948] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:914; }","duration":"499.694984ms","start":"2026-03-02T02:08:57.44108Z","end":"2026-03-02T02:08:57.940775Z","steps":["trace[499742948] 'range keys from in-memory index tree' (duration: 499.56818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.94082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.441063Z","time spent":"499.750996ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-02T02:08:57.940591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.529876ms","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-03-02T02:08:57.940914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.046221ms","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-03-02T02:08:57.940985Z","caller":"traceutil/trace.go:171","msg":"trace[1904278796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"460.957055ms","start":"2026-03-02T02:08:57.480019Z","end":"2026-03-02T02:08:57.940976Z","steps":["trace[1904278796] 'range keys from in-memory index tree' (duration: 460.404672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.940939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.322274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-02T02:08:57.941008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.480012Z","time spent":"460.990736ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:08:57.941039Z","caller":"traceutil/trace.go:171","msg":"trace[63890751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"462.233576ms","start":"2026-03-02T02:08:57.478772Z","end":"2026-03-02T02:08:57.941005Z","steps":["trace[63890751] 'range keys from in-memory index tree' (duration: 461.747934ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:57.94105Z","caller":"traceutil/trace.go:171","msg":"trace[105001536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"526.481048ms","start":"2026-03-02T02:08:57.414541Z","end":"2026-03-02T02:08:57.941022Z","steps":["trace[105001536] 'range keys from in-memory index tree' (duration: 526.036438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.941036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.664232ms","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-03-02T02:08:57.941102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.478757Z","time spent":"462.333378ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T02:08:57.941132Z","caller":"traceutil/trace.go:171","msg":"trace[316642321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"461.800966ms","start":"2026-03-02T02:08:57.47931Z","end":"2026-03-02T02:08:57.941111Z","steps":["trace[316642321] 'range keys from in-memory index tree' (duration: 461.468837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:57.941129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.414528Z","time spent":"526.582191ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:08:57.94118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.479302Z","time spent":"461.869097ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:08:58.202021Z","caller":"traceutil/trace.go:171","msg":"trace[1042007625] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"256.634673ms","start":"2026-03-02T02:08:57.945366Z","end":"2026-03-02T02:08:58.202001Z","steps":["trace[1042007625] 'process raft request' (duration: 256.50772ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:58.520608Z","caller":"traceutil/trace.go:171","msg":"trace[1706542965] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"575.181813ms","start":"2026-03-02T02:08:57.945382Z","end":"2026-03-02T02:08:58.520564Z","steps":["trace[1706542965] 'process raft request' (duration: 574.821444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.520794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.945364Z","time spent":"575.351758ms","remote":"127.0.0.1:33266","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-03-02T02:08:58.521021Z","caller":"traceutil/trace.go:171","msg":"trace[2109187957] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:957; }","duration":"101.896155ms","start":"2026-03-02T02:08:58.419094Z","end":"2026-03-02T02:08:58.52099Z","steps":["trace[2109187957] 'read index received' (duration: 101.189778ms)","trace[2109187957] 'applied index is now lower than readState.Index' (duration: 705.517µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:58.521177Z","caller":"traceutil/trace.go:171","msg":"trace[267084268] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"575.736316ms","start":"2026-03-02T02:08:57.945429Z","end":"2026-03-02T02:08:58.521166Z","steps":["trace[267084268] 'process raft request' (duration: 574.972598ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:58.521201Z","caller":"traceutil/trace.go:171","msg":"trace[1858705992] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"574.833565ms","start":"2026-03-02T02:08:57.946355Z","end":"2026-03-02T02:08:58.521189Z","steps":["trace[1858705992] 'process raft request' (duration: 574.546708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.521274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.946348Z","time spent":"574.890956ms","remote":"127.0.0.1:33266","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-03-02T02:08:58.521309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.945425Z","time spent":"575.788497ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:58.521416Z","caller":"traceutil/trace.go:171","msg":"trace[221773248] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"575.336297ms","start":"2026-03-02T02:08:57.946068Z","end":"2026-03-02T02:08:58.521404Z","steps":["trace[221773248] 'process raft request' (duration: 574.732893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.521468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.946052Z","time spent":"575.395458ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:08:58.521544Z","caller":"traceutil/trace.go:171","msg":"trace[343850100] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"575.04894ms","start":"2026-03-02T02:08:57.946484Z","end":"2026-03-02T02:08:58.521533Z","steps":["trace[343850100] 'process raft request' (duration: 574.472396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.52161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:57.946478Z","time spent":"575.099711ms","remote":"127.0.0.1:33266","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-03-02T02:08:58.521766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.664203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:08:58.521815Z","caller":"traceutil/trace.go:171","msg":"trace[957143447] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:920; }","duration":"102.749995ms","start":"2026-03-02T02:08:58.419051Z","end":"2026-03-02T02:08:58.521801Z","steps":["trace[957143447] 'agreement among raft nodes before linearized reading' (duration: 102.642843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.800967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.679778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:58.801229Z","caller":"traceutil/trace.go:171","msg":"trace[1682302683] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"364.947531ms","start":"2026-03-02T02:08:58.436252Z","end":"2026-03-02T02:08:58.8012Z","steps":["trace[1682302683] 'process raft request' (duration: 149.945215ms)","trace[1682302683] 'compare' (duration: 214.491284ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:58.801293Z","caller":"traceutil/trace.go:171","msg":"trace[1606932185] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"280.153101ms","start":"2026-03-02T02:08:58.521127Z","end":"2026-03-02T02:08:58.801281Z","steps":["trace[1606932185] 'read index received' (duration: 65.163485ms)","trace[1606932185] 'applied index is now lower than readState.Index' (duration: 214.988036ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:58.801397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:58.436228Z","time spent":"365.095484ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:08:58.801243Z","caller":"traceutil/trace.go:171","msg":"trace[1538509599] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"274.393454ms","start":"2026-03-02T02:08:58.526826Z","end":"2026-03-02T02:08:58.80122Z","steps":["trace[1538509599] 'process raft request' (duration: 274.281972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.801461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.72722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:58.801529Z","caller":"traceutil/trace.go:171","msg":"trace[910348074] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:922; }","duration":"303.805991ms","start":"2026-03-02T02:08:58.497707Z","end":"2026-03-02T02:08:58.801513Z","steps":["trace[910348074] 'agreement among raft nodes before linearized reading' (duration: 303.660458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.801597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.721319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-02T02:08:58.801661Z","caller":"traceutil/trace.go:171","msg":"trace[1336457947] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:922; }","duration":"276.816602ms","start":"2026-03-02T02:08:58.524829Z","end":"2026-03-02T02:08:58.801645Z","steps":["trace[1336457947] 'agreement among raft nodes before linearized reading' (duration: 276.570596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:58.801599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:58.497659Z","time spent":"303.908084ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-03-02T02:08:59.202355Z","caller":"traceutil/trace.go:171","msg":"trace[1533835157] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"396.174589ms","start":"2026-03-02T02:08:58.806159Z","end":"2026-03-02T02:08:59.202333Z","steps":["trace[1533835157] 'process raft request' (duration: 396.034366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:59.202497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:58.806136Z","time spent":"396.292742ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:08:59.385788Z","caller":"traceutil/trace.go:171","msg":"trace[226212835] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"434.261461ms","start":"2026-03-02T02:08:58.951507Z","end":"2026-03-02T02:08:59.385768Z","steps":["trace[226212835] 'read index received' (duration: 250.673411ms)","trace[226212835] 'applied index is now lower than readState.Index' (duration: 183.58739ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:59.385995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.466137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:59.386075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.557885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:59.386077Z","caller":"traceutil/trace.go:171","msg":"trace[71172088] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:924; }","duration":"434.565859ms","start":"2026-03-02T02:08:58.951492Z","end":"2026-03-02T02:08:59.386058Z","steps":["trace[71172088] 'agreement among raft nodes before linearized reading' (duration: 434.427186ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:59.386097Z","caller":"traceutil/trace.go:171","msg":"trace[1421528920] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:924; }","duration":"382.638787ms","start":"2026-03-02T02:08:59.003452Z","end":"2026-03-02T02:08:59.386091Z","steps":["trace[1421528920] 'agreement among raft nodes before linearized reading' (duration: 382.587226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:59.386116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:59.003429Z","time spent":"382.683218ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:59.38612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:58.951386Z","time spent":"434.724472ms","remote":"127.0.0.1:33126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:08:59.386232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.58784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:59.386245Z","caller":"traceutil/trace.go:171","msg":"trace[165777672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"110.627911ms","start":"2026-03-02T02:08:59.275613Z","end":"2026-03-02T02:08:59.386241Z","steps":["trace[165777672] 'agreement among raft nodes before linearized reading' (duration: 110.60711ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:59.386311Z","caller":"traceutil/trace.go:171","msg":"trace[203244574] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"579.398151ms","start":"2026-03-02T02:08:58.806899Z","end":"2026-03-02T02:08:59.386297Z","steps":["trace[203244574] 'process raft request' (duration: 488.720163ms)","trace[203244574] 'compare' (duration: 90.072504ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:59.386392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:58.806882Z","time spent":"579.455562ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:09:00.778671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862463,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:09:00.985689Z","caller":"traceutil/trace.go:171","msg":"trace[918246884] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"775.130461ms","start":"2026-03-02T02:09:00.210536Z","end":"2026-03-02T02:09:00.985666Z","steps":["trace[918246884] 'process raft request' (duration: 774.941826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:00.985838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.21051Z","time spent":"775.254174ms","remote":"127.0.0.1:33266","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-03-02T02:09:01.192784Z","caller":"traceutil/trace.go:171","msg":"trace[315957613] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:966; }","duration":"914.945286ms","start":"2026-03-02T02:09:00.277798Z","end":"2026-03-02T02:09:01.192744Z","steps":["trace[315957613] 'read index received' (duration: 707.673132ms)","trace[315957613] 'applied index is now lower than readState.Index' (duration: 207.270264ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:01.192919Z","caller":"traceutil/trace.go:171","msg":"trace[303083107] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"635.243207ms","start":"2026-03-02T02:09:00.55763Z","end":"2026-03-02T02:09:01.192873Z","steps":["trace[303083107] 'process raft request' (duration: 548.07268ms)","trace[303083107] 'compare' (duration: 86.843439ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:09:01.193094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.211962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:09:01.19313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.557609Z","time spent":"635.41421ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:09:01.193186Z","caller":"traceutil/trace.go:171","msg":"trace[1687123324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"915.371866ms","start":"2026-03-02T02:09:00.277794Z","end":"2026-03-02T02:09:01.193166Z","steps":["trace[1687123324] 'agreement among raft nodes before linearized reading' (duration: 915.176471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.193207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.639914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-02T02:09:01.193248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.277751Z","time spent":"915.477068ms","remote":"127.0.0.1:32954","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-03-02T02:09:01.193289Z","caller":"traceutil/trace.go:171","msg":"trace[1863190265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"667.752316ms","start":"2026-03-02T02:09:00.525516Z","end":"2026-03-02T02:09:01.193269Z","steps":["trace[1863190265] 'agreement among raft nodes before linearized reading' (duration: 667.549471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.193334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.525501Z","time spent":"667.824459ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:09:01.193456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.7475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:09:01.193481Z","caller":"traceutil/trace.go:171","msg":"trace[1891752687] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:926; }","duration":"241.775101ms","start":"2026-03-02T02:09:00.951697Z","end":"2026-03-02T02:09:01.193473Z","steps":["trace[1891752687] 'agreement among raft nodes before linearized reading' (duration: 241.73138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.193686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.501141ms","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-03-02T02:09:01.193715Z","caller":"traceutil/trace.go:171","msg":"trace[380558870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"667.569113ms","start":"2026-03-02T02:09:00.526138Z","end":"2026-03-02T02:09:01.193707Z","steps":["trace[380558870] 'agreement among raft nodes before linearized reading' (duration: 667.486951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.193761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.526119Z","time spent":"667.635234ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:09:01.193834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.178466ms","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-03-02T02:09:01.193878Z","caller":"traceutil/trace.go:171","msg":"trace[737664519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"668.262218ms","start":"2026-03-02T02:09:00.525602Z","end":"2026-03-02T02:09:01.193865Z","steps":["trace[737664519] 'agreement among raft nodes before linearized reading' (duration: 668.093334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.193909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.52558Z","time spent":"668.32218ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:09:01.193991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.796947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:09:01.194086Z","caller":"traceutil/trace.go:171","msg":"trace[2025248062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"667.934521ms","start":"2026-03-02T02:09:00.526123Z","end":"2026-03-02T02:09:01.194057Z","steps":["trace[2025248062] 'agreement among raft nodes before linearized reading' (duration: 667.665104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.194135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:00.526108Z","time spent":"668.019173ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:09:01.596752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.455267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:09:01.59692Z","caller":"traceutil/trace.go:171","msg":"trace[475618106] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"398.080606ms","start":"2026-03-02T02:09:01.198798Z","end":"2026-03-02T02:09:01.596878Z","steps":["trace[475618106] 'process raft request' (duration: 101.374593ms)","trace[475618106] 'compare' (duration: 296.276402ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:09:01.597006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.198779Z","time spent":"398.195398ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:09:01.823213Z","caller":"traceutil/trace.go:171","msg":"trace[1593755757] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"624.153103ms","start":"2026-03-02T02:09:01.199026Z","end":"2026-03-02T02:09:01.823179Z","steps":["trace[1593755757] 'process raft request' (duration: 534.272073ms)","trace[1593755757] 'compare' (duration: 89.683095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:09:01.823456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.198997Z","time spent":"624.311117ms","remote":"127.0.0.1:33266","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-03-02T02:09:01.823624Z","caller":"traceutil/trace.go:171","msg":"trace[876583709] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"624.47533ms","start":"2026-03-02T02:09:01.199115Z","end":"2026-03-02T02:09:01.823591Z","steps":["trace[876583709] 'process raft request' (duration: 624.002029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.823803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.199107Z","time spent":"624.618334ms","remote":"127.0.0.1:33266","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-03-02T02:09:01.823923Z","caller":"traceutil/trace.go:171","msg":"trace[965359028] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"551.731357ms","start":"2026-03-02T02:09:01.272097Z","end":"2026-03-02T02:09:01.823828Z","steps":["trace[965359028] 'process raft request' (duration: 551.377119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.824092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.272064Z","time spent":"551.950153ms","remote":"127.0.0.1:33266","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-03-02T02:09:01.824265Z","caller":"traceutil/trace.go:171","msg":"trace[344678690] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"625.008883ms","start":"2026-03-02T02:09:01.199244Z","end":"2026-03-02T02:09:01.824253Z","steps":["trace[344678690] 'process raft request' (duration: 624.127892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.824359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.199214Z","time spent":"625.100326ms","remote":"127.0.0.1:33266","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-03-02T02:09:01.880569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.536631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:09:01.881944Z","caller":"traceutil/trace.go:171","msg":"trace[421697809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"603.952705ms","start":"2026-03-02T02:09:01.277966Z","end":"2026-03-02T02:09:01.881919Z","steps":["trace[421697809] 'agreement among raft nodes before linearized reading' (duration: 602.519571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:01.882029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:01.277925Z","time spent":"604.080078ms","remote":"127.0.0.1:32954","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-03-02T02:09:01.880418Z","caller":"traceutil/trace.go:171","msg":"trace[670539922] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:968; }","duration":"602.374847ms","start":"2026-03-02T02:09:01.278017Z","end":"2026-03-02T02:09:01.880392Z","steps":["trace[670539922] 'read index received' (duration: 22.164115ms)","trace[670539922] 'applied index is now lower than readState.Index' (duration: 580.209452ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:07.8308Z","caller":"traceutil/trace.go:171","msg":"trace[1431210964] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"206.660719ms","start":"2026-03-02T02:09:07.624113Z","end":"2026-03-02T02:09:07.830774Z","steps":["trace[1431210964] 'process raft request' (duration: 206.462224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:08.620872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748862572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:09:09.293742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349796072s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:09:09.293925Z","caller":"traceutil/trace.go:171","msg":"trace[1720877009] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1024; }","duration":"1.173454043s","start":"2026-03-02T02:09:08.120459Z","end":"2026-03-02T02:09:09.293913Z","steps":["trace[1720877009] 'read index received' (duration: 502.609923ms)","trace[1720877009] 'applied index is now lower than readState.Index' (duration: 670.84338ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:09.294088Z","caller":"traceutil/trace.go:171","msg":"trace[1307371989] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.437438219s","start":"2026-03-02T02:09:07.856641Z","end":"2026-03-02T02:09:09.29408Z","steps":["trace[1307371989] 'process raft request' (duration: 87.249288ms)","trace[1307371989] 'compare' (duration: 1.349485124s)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:09:09.294138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:07.856623Z","time spent":"1.43749145s","remote":"127.0.0.1:33266","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-03-02T02:09:09.294266Z","caller":"traceutil/trace.go:171","msg":"trace[1916117487] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.435197986s","start":"2026-03-02T02:09:07.859059Z","end":"2026-03-02T02:09:09.294257Z","steps":["trace[1916117487] 'process raft request' (duration: 1.434761846s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:09.294306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:07.859038Z","time spent":"1.435247467s","remote":"127.0.0.1:33266","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-03-02T02:09:09.294816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016231516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:09:09.294878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.715579ms","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-03-02T02:09:09.294884Z","caller":"traceutil/trace.go:171","msg":"trace[87391469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"1.016336589s","start":"2026-03-02T02:09:08.278534Z","end":"2026-03-02T02:09:09.29487Z","steps":["trace[87391469] 'agreement among raft nodes before linearized reading' (duration: 1.016243016s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:09.294893Z","caller":"traceutil/trace.go:171","msg":"trace[1576617352] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.435282308s","start":"2026-03-02T02:09:07.859128Z","end":"2026-03-02T02:09:09.29441Z","steps":["trace[1576617352] 'process raft request' (duration: 1.434761525s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:09.294906Z","caller":"traceutil/trace.go:171","msg":"trace[1170700755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"249.776109ms","start":"2026-03-02T02:09:09.045121Z","end":"2026-03-02T02:09:09.294897Z","steps":["trace[1170700755] 'agreement among raft nodes before linearized reading' (duration: 249.666257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:09.29493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:08.278514Z","time spent":"1.016407s","remote":"127.0.0.1:32954","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-03-02T02:09:09.294928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.551708ms","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:509"} {"level":"warn","ts":"2026-03-02T02:09:09.294978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:07.859124Z","time spent":"1.4358024s","remote":"127.0.0.1:33266","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-03-02T02:09:09.295014Z","caller":"traceutil/trace.go:171","msg":"trace[1482304964] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:982; }","duration":"839.705883ms","start":"2026-03-02T02:09:08.455288Z","end":"2026-03-02T02:09:09.294994Z","steps":["trace[1482304964] 'agreement among raft nodes before linearized reading' (duration: 839.503638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:09.294812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174317873s","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:530"} {"level":"warn","ts":"2026-03-02T02:09:09.295061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:08.455269Z","time spent":"839.783614ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-02T02:09:09.295075Z","caller":"traceutil/trace.go:171","msg":"trace[1570435716] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:982; }","duration":"1.174649191s","start":"2026-03-02T02:09:08.120419Z","end":"2026-03-02T02:09:09.295069Z","steps":["trace[1570435716] 'agreement among raft nodes before linearized reading' (duration: 1.174246902s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:09.295099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:08.120402Z","time spent":"1.174690052s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-02T02:09:09.403834Z","caller":"traceutil/trace.go:171","msg":"trace[298033828] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"101.853434ms","start":"2026-03-02T02:09:09.301959Z","end":"2026-03-02T02:09:09.403812Z","steps":["trace[298033828] 'process raft request' (duration: 97.48684ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:05.820188Z","caller":"traceutil/trace.go:171","msg":"trace[1804820167] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"113.59329ms","start":"2026-03-02T02:10:05.706573Z","end":"2026-03-02T02:10:05.820167Z","steps":["trace[1804820167] 'process raft request' (duration: 113.557399ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:05.820227Z","caller":"traceutil/trace.go:171","msg":"trace[112418870] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"114.140473ms","start":"2026-03-02T02:10:05.706059Z","end":"2026-03-02T02:10:05.8202Z","steps":["trace[112418870] 'process raft request' (duration: 37.754784ms)","trace[112418870] 'compare' (duration: 76.204215ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:12:20.077949Z","caller":"traceutil/trace.go:171","msg":"trace[1028220125] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"128.783653ms","start":"2026-03-02T02:12:19.949134Z","end":"2026-03-02T02:12:20.077918Z","steps":["trace[1028220125] 'process raft request' (duration: 49.250689ms)","trace[1028220125] 'compare' (duration: 79.415151ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:12:20.254153Z","caller":"traceutil/trace.go:171","msg":"trace[404609170] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"104.850224ms","start":"2026-03-02T02:12:20.149275Z","end":"2026-03-02T02:12:20.254125Z","steps":["trace[404609170] 'process raft request' (duration: 32.809215ms)","trace[404609170] 'compare' (duration: 71.918546ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:12:31.761913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.39038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:12:31.762037Z","caller":"traceutil/trace.go:171","msg":"trace[9175537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1842; }","duration":"483.586534ms","start":"2026-03-02T02:12:31.278422Z","end":"2026-03-02T02:12:31.762008Z","steps":["trace[9175537] 'range keys from in-memory index tree' (duration: 483.272207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:31.7621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:31.278384Z","time spent":"483.692987ms","remote":"127.0.0.1:32954","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-03-02T02:12:31.762308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.768231ms","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-03-02T02:12:31.762898Z","caller":"traceutil/trace.go:171","msg":"trace[21054692] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1842; }","duration":"180.438137ms","start":"2026-03-02T02:12:31.582431Z","end":"2026-03-02T02:12:31.762869Z","steps":["trace[21054692] 'range keys from in-memory index tree' (duration: 179.360412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:53.145002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748865512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:12:53.645354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748865512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:12:54.146002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740459748865512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:12:54.349794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.708989903s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:12:54.350426Z","caller":"traceutil/trace.go:171","msg":"trace[870545199] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"1.70967954s","start":"2026-03-02T02:12:52.640719Z","end":"2026-03-02T02:12:54.350398Z","steps":["trace[870545199] 'process raft request' (duration: 1.709442934s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.350813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.640707Z","time spent":"1.709802672s","remote":"127.0.0.1:33266","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-03-02T02:12:54.351686Z","caller":"traceutil/trace.go:171","msg":"trace[1318945686] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2023; }","duration":"1.70758168s","start":"2026-03-02T02:12:52.644087Z","end":"2026-03-02T02:12:54.351669Z","steps":["trace[1318945686] 'read index received' (duration: 1.706000153s)","trace[1318945686] 'applied index is now lower than readState.Index' (duration: 1.580707ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:12:54.351793Z","caller":"traceutil/trace.go:171","msg":"trace[1430410760] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"1.709613717s","start":"2026-03-02T02:12:52.642148Z","end":"2026-03-02T02:12:54.351762Z","steps":["trace[1430410760] 'process raft request' (duration: 1.709450564s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.351905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.642137Z","time spent":"1.70967608s","remote":"127.0.0.1:33266","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-03-02T02:12:54.352002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705735887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" ","response":"range_response_count:1 size:919"} {"level":"info","ts":"2026-03-02T02:12:54.352077Z","caller":"traceutil/trace.go:171","msg":"trace[112737921] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-config; range_end:; response_count:1; response_revision:1934; }","duration":"1.705828869s","start":"2026-03-02T02:12:52.646224Z","end":"2026-03-02T02:12:54.352053Z","steps":["trace[112737921] 'agreement among raft nodes before linearized reading' (duration: 1.705581623s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.35213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.646218Z","time spent":"1.705902971s","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":942,"request content":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" "} {"level":"warn","ts":"2026-03-02T02:12:54.35213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.686965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-02T02:12:54.35217Z","caller":"traceutil/trace.go:171","msg":"trace[996368246] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1934; }","duration":"226.767447ms","start":"2026-03-02T02:12:54.125391Z","end":"2026-03-02T02:12:54.352158Z","steps":["trace[996368246] 'agreement among raft nodes before linearized reading' (duration: 226.717425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.352161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073053956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:12:54.352239Z","caller":"traceutil/trace.go:171","msg":"trace[1244679125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1934; }","duration":"1.073167179s","start":"2026-03-02T02:12:53.279044Z","end":"2026-03-02T02:12:54.352211Z","steps":["trace[1244679125] 'agreement among raft nodes before linearized reading' (duration: 1.073039316s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.352288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:53.279022Z","time spent":"1.073254411s","remote":"127.0.0.1:32954","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-03-02T02:12:54.352305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.994553ms","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-03-02T02:12:54.352323Z","caller":"traceutil/trace.go:171","msg":"trace[521644536] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1934; }","duration":"568.050923ms","start":"2026-03-02T02:12:53.784267Z","end":"2026-03-02T02:12:54.352318Z","steps":["trace[521644536] 'agreement among raft nodes before linearized reading' (duration: 568.001182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.352089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.899204ms","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-03-02T02:12:54.352337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:53.78425Z","time spent":"568.081875ms","remote":"127.0.0.1:33266","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":"warn","ts":"2026-03-02T02:12:54.352304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.47596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:64046"} {"level":"info","ts":"2026-03-02T02:12:54.352362Z","caller":"traceutil/trace.go:171","msg":"trace[913313566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1934; }","duration":"569.19516ms","start":"2026-03-02T02:12:53.783152Z","end":"2026-03-02T02:12:54.352347Z","steps":["trace[913313566] 'agreement among raft nodes before linearized reading' (duration: 568.846412ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:12:54.352411Z","caller":"traceutil/trace.go:171","msg":"trace[2049078151] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1934; }","duration":"226.638884ms","start":"2026-03-02T02:12:54.125745Z","end":"2026-03-02T02:12:54.352383Z","steps":["trace[2049078151] 'agreement among raft nodes before linearized reading' (duration: 226.260295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.352421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650008598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-public/\" range_end:\"/registry/jobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:12:54.352483Z","caller":"traceutil/trace.go:171","msg":"trace[284646847] range","detail":"{range_begin:/registry/jobs/kube-public/; range_end:/registry/jobs/kube-public0; response_count:0; response_revision:1934; }","duration":"1.65009485s","start":"2026-03-02T02:12:52.702371Z","end":"2026-03-02T02:12:54.352466Z","steps":["trace[284646847] 'agreement among raft nodes before linearized reading' (duration: 1.649993337s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.352534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70843393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-02T02:12:54.352611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668014698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T02:12:54.352606Z","caller":"traceutil/trace.go:171","msg":"trace[232947833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1934; }","duration":"1.708515362s","start":"2026-03-02T02:12:52.644069Z","end":"2026-03-02T02:12:54.352584Z","steps":["trace[232947833] 'agreement among raft nodes before linearized reading' (duration: 1.708277696s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:12:54.352634Z","caller":"traceutil/trace.go:171","msg":"trace[302025564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1934; }","duration":"1.668060519s","start":"2026-03-02T02:12:52.684568Z","end":"2026-03-02T02:12:54.352629Z","steps":["trace[302025564] 'agreement among raft nodes before linearized reading' (duration: 1.667990417s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.35265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.68455Z","time spent":"1.66809685s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:12:54.352654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.644064Z","time spent":"1.708577324s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:12:54.352547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.702359Z","time spent":"1.650176281s","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/kube-public/\" range_end:\"/registry/jobs/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-03-02T02:12:54.352337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6900003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"warn","ts":"2026-03-02T02:12:54.352434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:53.783128Z","time spent":"569.289673ms","remote":"127.0.0.1:33266","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-03-02T02:12:54.353124Z","caller":"traceutil/trace.go:171","msg":"trace[990256471] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:1934; }","duration":"1.690789289s","start":"2026-03-02T02:12:52.662317Z","end":"2026-03-02T02:12:54.353106Z","steps":["trace[990256471] 'agreement among raft nodes before linearized reading' (duration: 1.689912478s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.353181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.6623Z","time spent":"1.69086742s","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":834,"request content":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" "} {"level":"warn","ts":"2026-03-02T02:12:54.35326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667460725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" limit:500 ","response":"range_response_count:3 size:20160"} {"level":"info","ts":"2026-03-02T02:12:54.353475Z","caller":"traceutil/trace.go:171","msg":"trace[350899806] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:3; response_revision:1934; }","duration":"1.667866534s","start":"2026-03-02T02:12:52.685558Z","end":"2026-03-02T02:12:54.353424Z","steps":["trace[350899806] 'agreement among raft nodes before linearized reading' (duration: 1.666475652s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:12:54.353576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:12:52.685547Z","time spent":"1.668016487s","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":3,"response size":20183,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" limit:500 "}