{"level":"warn","ts":"2026-02-24T19:57:32.518276Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:57:32.51836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-02-24T19:57:32.518425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:57:32.518435Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-24T19:57:32.51845Z","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-02-24T19:57:32.519104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-24T19:57:32.519191Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-02-24T19:57:32.524364Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.943121ms"} {"level":"info","ts":"2026-02-24T19:57:32.53232Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-24T19:57:32.532421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T19:57:32.532448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-24T19:57:32.532462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T19:57:32.532478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-24T19:57:32.532524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-24T19:57:32.542049Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T19:57:32.54344Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T19:57:32.544667Z","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-02-24T19:57:32.549467Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T19:57:32.550431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T19:57:32.55065Z","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-02-24T19:57:32.550724Z","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-02-24T19:57:32.550739Z","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-02-24T19:57:32.551115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-24T19:57:32.551263Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-24T19:57:32.556891Z","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-02-24T19:57:32.557056Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T19:57:32.557078Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T19:57:32.557104Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-24T19:57:32.557123Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-24T19:57:32.634978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T19:57:32.635027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T19:57:32.635071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-24T19:57:32.635086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-24T19:57:32.635093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-24T19:57:32.635101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-24T19:57:32.63511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-24T19:57:32.643131Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T19:57:32.643277Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:57:32.643396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:57:32.643654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:57:32.645181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T19:57:32.645265Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T19:57:32.64696Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-24T19:57:32.647002Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T19:57:32.647155Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:57:32.647258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:57:32.647323Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-24T19:57:43.981053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.017646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:57:43.981255Z","caller":"traceutil/trace.go:171","msg":"trace[1210918246] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"206.131776ms","start":"2026-02-24T19:57:43.775096Z","end":"2026-02-24T19:57:43.981228Z","steps":["trace[1210918246] 'process raft request' (duration: 94.221509ms)","trace[1210918246] 'compare' (duration: 110.633878ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:57:43.981477Z","caller":"traceutil/trace.go:171","msg":"trace[1644692300] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"183.746193ms","start":"2026-02-24T19:57:43.797715Z","end":"2026-02-24T19:57:43.981461Z","steps":["trace[1644692300] 'process raft request' (duration: 183.565059ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:43.981535Z","caller":"traceutil/trace.go:171","msg":"trace[1995335998] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"188.172523ms","start":"2026-02-24T19:57:43.793319Z","end":"2026-02-24T19:57:43.981491Z","steps":["trace[1995335998] 'read index received' (duration: 76.00374ms)","trace[1995335998] 'applied index is now lower than readState.Index' (duration: 112.162333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:57:43.98171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.348047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:57:43.981755Z","caller":"traceutil/trace.go:171","msg":"trace[1649561677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"188.459819ms","start":"2026-02-24T19:57:43.793284Z","end":"2026-02-24T19:57:43.981744Z","steps":["trace[1649561677] 'agreement among raft nodes before linearized reading' (duration: 188.357567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:57:43.981766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.227934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-02-24T19:57:43.981816Z","caller":"traceutil/trace.go:171","msg":"trace[731670594] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:386; }","duration":"108.332207ms","start":"2026-02-24T19:57:43.873472Z","end":"2026-02-24T19:57:43.981805Z","steps":["trace[731670594] 'agreement among raft nodes before linearized reading' (duration: 108.189254ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:43.981414Z","caller":"traceutil/trace.go:171","msg":"trace[1382861437] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"183.419436ms","start":"2026-02-24T19:57:43.797979Z","end":"2026-02-24T19:57:43.981399Z","steps":["trace[1382861437] 'process raft request' (duration: 183.364255ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:43.981505Z","caller":"traceutil/trace.go:171","msg":"trace[366600830] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"184.94367ms","start":"2026-02-24T19:57:43.796536Z","end":"2026-02-24T19:57:43.98148Z","steps":["trace[366600830] 'process raft request' (duration: 184.701634ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:43.982037Z","caller":"traceutil/trace.go:171","msg":"trace[133367977] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"185.541243ms","start":"2026-02-24T19:57:43.796481Z","end":"2026-02-24T19:57:43.982022Z","steps":["trace[133367977] 'process raft request' (duration: 184.697064ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:57.365773Z","caller":"traceutil/trace.go:171","msg":"trace[27325822] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"572.848024ms","start":"2026-02-24T19:57:56.792904Z","end":"2026-02-24T19:57:57.365752Z","steps":["trace[27325822] 'process raft request' (duration: 572.66423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:57:57.365872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.991438ms","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-02-24T19:57:57.365937Z","caller":"traceutil/trace.go:171","msg":"trace[1459547833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"425.10247ms","start":"2026-02-24T19:57:56.940819Z","end":"2026-02-24T19:57:57.365921Z","steps":["trace[1459547833] 'agreement among raft nodes before linearized reading' (duration: 424.989188ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:57:57.365729Z","caller":"traceutil/trace.go:171","msg":"trace[1047970369] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"424.824243ms","start":"2026-02-24T19:57:56.940874Z","end":"2026-02-24T19:57:57.365699Z","steps":["trace[1047970369] 'read index received' (duration: 424.640629ms)","trace[1047970369] 'applied index is now lower than readState.Index' (duration: 182.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:57:57.366021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:57:56.9408Z","time spent":"425.207863ms","remote":"127.0.0.1:54018","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-02-24T19:57:57.366148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:57:56.792886Z","time spent":"572.952485ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:57:57.817193Z","caller":"traceutil/trace.go:171","msg":"trace[1775905446] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"447.635998ms","start":"2026-02-24T19:57:57.369538Z","end":"2026-02-24T19:57:57.817174Z","steps":["trace[1775905446] 'process raft request' (duration: 447.258209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:57:57.817394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:57:57.369518Z","time spent":"447.76782ms","remote":"127.0.0.1:54018","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-02-24T19:58:01.572085Z","caller":"traceutil/trace.go:171","msg":"trace[1211518979] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"188.583611ms","start":"2026-02-24T19:58:01.383473Z","end":"2026-02-24T19:58:01.572057Z","steps":["trace[1211518979] 'process raft request' (duration: 188.383977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:02.046322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.795874ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T19:58:02.046429Z","caller":"traceutil/trace.go:171","msg":"trace[1930739159] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"276.250832ms","start":"2026-02-24T19:58:01.770161Z","end":"2026-02-24T19:58:02.046412Z","steps":["trace[1930739159] 'read index received' (duration: 72.366597ms)","trace[1930739159] 'applied index is now lower than readState.Index' (duration: 203.882825ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:02.046571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.283135ms","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-02-24T19:58:02.046558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.375715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:02.046627Z","caller":"traceutil/trace.go:171","msg":"trace[2138043474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"276.494698ms","start":"2026-02-24T19:58:01.770115Z","end":"2026-02-24T19:58:02.04661Z","steps":["trace[2138043474] 'agreement among raft nodes before linearized reading' (duration: 276.369095ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:02.046654Z","caller":"traceutil/trace.go:171","msg":"trace[614835586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"216.367457ms","start":"2026-02-24T19:58:01.830233Z","end":"2026-02-24T19:58:02.0466Z","steps":["trace[614835586] 'agreement among raft nodes before linearized reading' (duration: 216.274815ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:03.750006Z","caller":"traceutil/trace.go:171","msg":"trace[387614548] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"170.899544ms","start":"2026-02-24T19:58:03.579084Z","end":"2026-02-24T19:58:03.749983Z","steps":["trace[387614548] 'process raft request' (duration: 170.763491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:03.878248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.541263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:03.87831Z","caller":"traceutil/trace.go:171","msg":"trace[238303810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"109.663536ms","start":"2026-02-24T19:58:03.768631Z","end":"2026-02-24T19:58:03.878295Z","steps":["trace[238303810] 'range keys from in-memory index tree' (duration: 109.443282ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:07.905121Z","caller":"traceutil/trace.go:171","msg":"trace[1143453759] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"134.654418ms","start":"2026-02-24T19:58:07.770443Z","end":"2026-02-24T19:58:07.905098Z","steps":["trace[1143453759] 'read index received' (duration: 134.545485ms)","trace[1143453759] 'applied index is now lower than readState.Index' (duration: 107.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:07.905147Z","caller":"traceutil/trace.go:171","msg":"trace[758575414] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"139.749393ms","start":"2026-02-24T19:58:07.765373Z","end":"2026-02-24T19:58:07.905122Z","steps":["trace[758575414] 'process raft request' (duration: 139.556839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:07.90526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.785362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:07.905292Z","caller":"traceutil/trace.go:171","msg":"trace[413766996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"134.864453ms","start":"2026-02-24T19:58:07.770417Z","end":"2026-02-24T19:58:07.905282Z","steps":["trace[413766996] 'agreement among raft nodes before linearized reading' (duration: 134.787811ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:10.212255Z","caller":"traceutil/trace.go:171","msg":"trace[1834240338] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"128.361587ms","start":"2026-02-24T19:58:10.083868Z","end":"2026-02-24T19:58:10.21223Z","steps":["trace[1834240338] 'read index received' (duration: 128.105192ms)","trace[1834240338] 'applied index is now lower than readState.Index' (duration: 252.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:10.212376Z","caller":"traceutil/trace.go:171","msg":"trace[1320975083] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"299.402694ms","start":"2026-02-24T19:58:09.912927Z","end":"2026-02-24T19:58:10.21233Z","steps":["trace[1320975083] 'process raft request' (duration: 299.118747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:10.212514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.635573ms","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-02-24T19:58:10.212572Z","caller":"traceutil/trace.go:171","msg":"trace[2118554545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"128.707225ms","start":"2026-02-24T19:58:10.083852Z","end":"2026-02-24T19:58:10.212559Z","steps":["trace[2118554545] 'agreement among raft nodes before linearized reading' (duration: 128.50139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:10.212544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.870171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j7nfc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T19:58:10.212677Z","caller":"traceutil/trace.go:171","msg":"trace[1901364515] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j7nfc; range_end:; response_count:1; response_revision:526; }","duration":"102.037135ms","start":"2026-02-24T19:58:10.110622Z","end":"2026-02-24T19:58:10.21266Z","steps":["trace[1901364515] 'agreement among raft nodes before linearized reading' (duration: 101.741598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:10.948471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.325179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:58:10.948758Z","caller":"traceutil/trace.go:171","msg":"trace[905884396] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:544; }","duration":"514.02165ms","start":"2026-02-24T19:58:10.43472Z","end":"2026-02-24T19:58:10.948742Z","steps":["trace[905884396] 'read index received' (duration: 5.633806ms)","trace[905884396] 'applied index is now lower than readState.Index' (duration: 508.386914ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:10.948847Z","caller":"traceutil/trace.go:171","msg":"trace[1819439511] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"729.239421ms","start":"2026-02-24T19:58:10.219597Z","end":"2026-02-24T19:58:10.948836Z","steps":["trace[1819439511] 'process raft request' (duration: 220.812097ms)","trace[1819439511] 'compare' (duration: 507.200517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:10.94891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:10.219569Z","time spent":"729.310392ms","remote":"127.0.0.1:54018","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-02-24T19:58:10.949019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.628217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:10.949103Z","caller":"traceutil/trace.go:171","msg":"trace[915460357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"178.75893ms","start":"2026-02-24T19:58:10.770327Z","end":"2026-02-24T19:58:10.949086Z","steps":["trace[915460357] 'agreement among raft nodes before linearized reading' (duration: 178.569946ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:10.949157Z","caller":"traceutil/trace.go:171","msg":"trace[1153115652] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"722.631962ms","start":"2026-02-24T19:58:10.226497Z","end":"2026-02-24T19:58:10.949129Z","steps":["trace[1153115652] 'process raft request' (duration: 722.08792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:10.949264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.49716ms","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-02-24T19:58:10.949319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:10.226485Z","time spent":"722.746555ms","remote":"127.0.0.1:53936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:58:10.949351Z","caller":"traceutil/trace.go:171","msg":"trace[47383163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"514.653324ms","start":"2026-02-24T19:58:10.43468Z","end":"2026-02-24T19:58:10.949333Z","steps":["trace[47383163] 'agreement among raft nodes before linearized reading' (duration: 514.181533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:10.949444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:10.434658Z","time spent":"514.774686ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T19:58:12.69451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.815168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T19:58:12.694635Z","caller":"traceutil/trace.go:171","msg":"trace[1284301002] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"476.843744ms","start":"2026-02-24T19:58:12.217773Z","end":"2026-02-24T19:58:12.694617Z","steps":["trace[1284301002] 'read index received' (duration: 68.112µs)","trace[1284301002] 'applied index is now lower than readState.Index' (duration: 476.774162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:12.694889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.11096ms","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-02-24T19:58:12.694927Z","caller":"traceutil/trace.go:171","msg":"trace[332150042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"477.218153ms","start":"2026-02-24T19:58:12.217698Z","end":"2026-02-24T19:58:12.694916Z","steps":["trace[332150042] 'agreement among raft nodes before linearized reading' (duration: 477.015829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:12.694991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:12.217672Z","time spent":"477.310845ms","remote":"127.0.0.1:54018","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-02-24T19:58:12.694975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.214323ms","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-02-24T19:58:12.695045Z","caller":"traceutil/trace.go:171","msg":"trace[1280495538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"385.369727ms","start":"2026-02-24T19:58:12.309658Z","end":"2026-02-24T19:58:12.695028Z","steps":["trace[1280495538] 'agreement among raft nodes before linearized reading' (duration: 385.102981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:12.695084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:12.309634Z","time spent":"385.442009ms","remote":"127.0.0.1:54018","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-02-24T19:58:12.888233Z","caller":"traceutil/trace.go:171","msg":"trace[1953798596] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"189.020681ms","start":"2026-02-24T19:58:12.699188Z","end":"2026-02-24T19:58:12.888209Z","steps":["trace[1953798596] 'process raft request' (duration: 188.407738ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:12.888123Z","caller":"traceutil/trace.go:171","msg":"trace[388802569] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"119.6409ms","start":"2026-02-24T19:58:12.768456Z","end":"2026-02-24T19:58:12.888097Z","steps":["trace[388802569] 'read index received' (duration: 119.051987ms)","trace[388802569] 'applied index is now lower than readState.Index' (duration: 587.853µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:12.888551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.079701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:12.888635Z","caller":"traceutil/trace.go:171","msg":"trace[647684661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"120.215773ms","start":"2026-02-24T19:58:12.768405Z","end":"2026-02-24T19:58:12.88862Z","steps":["trace[647684661] 'agreement among raft nodes before linearized reading' (duration: 120.08224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:14.974395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.519295ms","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-02-24T19:58:14.974469Z","caller":"traceutil/trace.go:171","msg":"trace[841898423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"226.642978ms","start":"2026-02-24T19:58:14.747809Z","end":"2026-02-24T19:58:14.974452Z","steps":["trace[841898423] 'range keys from in-memory index tree' (duration: 226.395702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:14.974624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.660235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:14.974655Z","caller":"traceutil/trace.go:171","msg":"trace[491502541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"205.710246ms","start":"2026-02-24T19:58:14.768936Z","end":"2026-02-24T19:58:14.974647Z","steps":["trace[491502541] 'range keys from in-memory index tree' (duration: 205.605984ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:31.560393Z","caller":"traceutil/trace.go:171","msg":"trace[1794800921] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"438.639675ms","start":"2026-02-24T19:58:31.121732Z","end":"2026-02-24T19:58:31.560372Z","steps":["trace[1794800921] 'read index received' (duration: 438.493582ms)","trace[1794800921] 'applied index is now lower than readState.Index' (duration: 145.103µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:31.560449Z","caller":"traceutil/trace.go:171","msg":"trace[2063759021] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"447.507524ms","start":"2026-02-24T19:58:31.112911Z","end":"2026-02-24T19:58:31.560418Z","steps":["trace[2063759021] 'process raft request' (duration: 447.30759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:31.56063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.889751ms","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-02-24T19:58:31.560675Z","caller":"traceutil/trace.go:171","msg":"trace[1143546023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"438.973442ms","start":"2026-02-24T19:58:31.121682Z","end":"2026-02-24T19:58:31.560656Z","steps":["trace[1143546023] 'agreement among raft nodes before linearized reading' (duration: 438.827949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:31.560705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.12166Z","time spent":"439.039054ms","remote":"127.0.0.1:54018","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-02-24T19:58:31.560825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.53342ms","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-02-24T19:58:31.560907Z","caller":"traceutil/trace.go:171","msg":"trace[217001888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"429.645673ms","start":"2026-02-24T19:58:31.13124Z","end":"2026-02-24T19:58:31.560885Z","steps":["trace[217001888] 'agreement among raft nodes before linearized reading' (duration: 429.441618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:31.561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.770056ms","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-02-24T19:58:31.561151Z","caller":"traceutil/trace.go:171","msg":"trace[34677070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"429.960519ms","start":"2026-02-24T19:58:31.131145Z","end":"2026-02-24T19:58:31.561105Z","steps":["trace[34677070] 'agreement among raft nodes before linearized reading' (duration: 429.511259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:31.560947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.131234Z","time spent":"429.703514ms","remote":"127.0.0.1:54018","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-02-24T19:58:31.560606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.11289Z","time spent":"447.632718ms","remote":"127.0.0.1:54018","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-02-24T19:58:31.56121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.13113Z","time spent":"430.069652ms","remote":"127.0.0.1:54018","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-02-24T19:58:32.065542Z","caller":"traceutil/trace.go:171","msg":"trace[1863228012] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"500.826444ms","start":"2026-02-24T19:58:31.564679Z","end":"2026-02-24T19:58:32.065505Z","steps":["trace[1863228012] 'process raft request' (duration: 499.952245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:32.065728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.564645Z","time spent":"500.990828ms","remote":"127.0.0.1:54018","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-02-24T19:58:32.066151Z","caller":"traceutil/trace.go:171","msg":"trace[344756758] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"500.963296ms","start":"2026-02-24T19:58:31.565174Z","end":"2026-02-24T19:58:32.066137Z","steps":["trace[344756758] 'process raft request' (duration: 500.781372ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:32.066153Z","caller":"traceutil/trace.go:171","msg":"trace[1599553546] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"500.909045ms","start":"2026-02-24T19:58:31.565221Z","end":"2026-02-24T19:58:32.066131Z","steps":["trace[1599553546] 'process raft request' (duration: 500.857054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:32.066209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.565146Z","time spent":"501.035319ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:58:32.066334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.56519Z","time spent":"501.049098ms","remote":"127.0.0.1:54018","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-02-24T19:58:32.07141Z","caller":"traceutil/trace.go:171","msg":"trace[290196676] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:737; }","duration":"329.690925ms","start":"2026-02-24T19:58:31.741687Z","end":"2026-02-24T19:58:32.071378Z","steps":["trace[290196676] 'read index received' (duration: 322.973774ms)","trace[290196676] 'applied index is now lower than readState.Index' (duration: 6.714741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:32.071581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.885379ms","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-02-24T19:58:32.071645Z","caller":"traceutil/trace.go:171","msg":"trace[1852821784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"329.95951ms","start":"2026-02-24T19:58:31.741671Z","end":"2026-02-24T19:58:32.071631Z","steps":["trace[1852821784] 'agreement among raft nodes before linearized reading' (duration: 329.777546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:32.071688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:31.741614Z","time spent":"330.067833ms","remote":"127.0.0.1:54018","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-02-24T19:58:33.99937Z","caller":"traceutil/trace.go:171","msg":"trace[2022653615] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"226.142025ms","start":"2026-02-24T19:58:33.773208Z","end":"2026-02-24T19:58:33.99935Z","steps":["trace[2022653615] 'read index received' (duration: 226.020542ms)","trace[2022653615] 'applied index is now lower than readState.Index' (duration: 120.503µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:33.999418Z","caller":"traceutil/trace.go:171","msg":"trace[1515829014] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"429.779005ms","start":"2026-02-24T19:58:33.569611Z","end":"2026-02-24T19:58:33.99939Z","steps":["trace[1515829014] 'process raft request' (duration: 429.622661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:33.999473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.257167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:33.999499Z","caller":"traceutil/trace.go:171","msg":"trace[939791008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"226.326969ms","start":"2026-02-24T19:58:33.773164Z","end":"2026-02-24T19:58:33.999491Z","steps":["trace[939791008] 'agreement among raft nodes before linearized reading' (duration: 226.259318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:33.999554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:33.569584Z","time spent":"429.897978ms","remote":"127.0.0.1:54018","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-02-24T19:58:35.715552Z","caller":"traceutil/trace.go:171","msg":"trace[960546891] transaction","detail":"{read_only:false; number_of_response:1; response_revision:724; }","duration":"148.676352ms","start":"2026-02-24T19:58:35.566852Z","end":"2026-02-24T19:58:35.715529Z","steps":["trace[960546891] 'process raft request' (duration: 148.395706ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:35.866556Z","caller":"traceutil/trace.go:171","msg":"trace[1168277326] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"143.542414ms","start":"2026-02-24T19:58:35.72293Z","end":"2026-02-24T19:58:35.866473Z","steps":["trace[1168277326] 'process raft request' (duration: 143.186736ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:36.265405Z","caller":"traceutil/trace.go:171","msg":"trace[911120962] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:751; }","duration":"101.074257ms","start":"2026-02-24T19:58:36.16431Z","end":"2026-02-24T19:58:36.265385Z","steps":["trace[911120962] 'read index received' (duration: 5.896236ms)","trace[911120962] 'applied index is now lower than readState.Index' (duration: 95.17735ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:36.265524Z","caller":"traceutil/trace.go:171","msg":"trace[2132271408] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"113.83884ms","start":"2026-02-24T19:58:36.151654Z","end":"2026-02-24T19:58:36.265493Z","steps":["trace[2132271408] 'process raft request' (duration: 113.675166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:36.265585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.25475ms","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-02-24T19:58:36.265622Z","caller":"traceutil/trace.go:171","msg":"trace[411063354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"101.324672ms","start":"2026-02-24T19:58:36.164287Z","end":"2026-02-24T19:58:36.265612Z","steps":["trace[411063354] 'agreement among raft nodes before linearized reading' (duration: 101.184708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:36.265562Z","caller":"traceutil/trace.go:171","msg":"trace[1164889275] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"115.423387ms","start":"2026-02-24T19:58:36.150116Z","end":"2026-02-24T19:58:36.26554Z","steps":["trace[1164889275] 'process raft request' (duration: 99.225965ms)","trace[1164889275] 'compare' (duration: 15.793823ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:36.471775Z","caller":"traceutil/trace.go:171","msg":"trace[1933771416] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"130.573866ms","start":"2026-02-24T19:58:36.341179Z","end":"2026-02-24T19:58:36.471753Z","steps":["trace[1933771416] 'read index received' (duration: 130.362332ms)","trace[1933771416] 'applied index is now lower than readState.Index' (duration: 207.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:36.471894Z","caller":"traceutil/trace.go:171","msg":"trace[1657991920] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"135.296305ms","start":"2026-02-24T19:58:36.336554Z","end":"2026-02-24T19:58:36.471851Z","steps":["trace[1657991920] 'process raft request' (duration: 135.052249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:36.472064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.866843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T19:58:36.472136Z","caller":"traceutil/trace.go:171","msg":"trace[1686553309] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:733; }","duration":"130.952425ms","start":"2026-02-24T19:58:36.341159Z","end":"2026-02-24T19:58:36.472111Z","steps":["trace[1686553309] 'agreement among raft nodes before linearized reading' (duration: 130.76596ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:54.528946Z","caller":"traceutil/trace.go:171","msg":"trace[1057670001] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"171.929868ms","start":"2026-02-24T19:58:54.356987Z","end":"2026-02-24T19:58:54.528917Z","steps":["trace[1057670001] 'process raft request' (duration: 171.764785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:54.788835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.496729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:58:54.788939Z","caller":"traceutil/trace.go:171","msg":"trace[1030544471] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"391.257286ms","start":"2026-02-24T19:58:54.397666Z","end":"2026-02-24T19:58:54.788923Z","steps":["trace[1030544471] 'read index received' (duration: 131.20646ms)","trace[1030544471] 'applied index is now lower than readState.Index' (duration: 260.049656ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:54.789004Z","caller":"traceutil/trace.go:171","msg":"trace[1349804425] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"431.769699ms","start":"2026-02-24T19:58:54.357182Z","end":"2026-02-24T19:58:54.788952Z","steps":["trace[1349804425] 'process raft request' (duration: 329.079215ms)","trace[1349804425] 'compare' (duration: 102.346306ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:54.789066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.396669ms","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-02-24T19:58:54.789095Z","caller":"traceutil/trace.go:171","msg":"trace[445351313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"391.446351ms","start":"2026-02-24T19:58:54.397639Z","end":"2026-02-24T19:58:54.789086Z","steps":["trace[445351313] 'agreement among raft nodes before linearized reading' (duration: 391.327808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:54.789137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:54.397627Z","time spent":"391.502602ms","remote":"127.0.0.1:54018","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-02-24T19:58:54.789186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:54.357165Z","time spent":"431.920112ms","remote":"127.0.0.1:54018","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-02-24T19:58:56.851164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.099823ms","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-02-24T19:58:56.851244Z","caller":"traceutil/trace.go:171","msg":"trace[112762330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"318.236006ms","start":"2026-02-24T19:58:56.532991Z","end":"2026-02-24T19:58:56.851227Z","steps":["trace[112762330] 'range keys from in-memory index tree' (duration: 318.009311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:56.85128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:56.532973Z","time spent":"318.297717ms","remote":"127.0.0.1:54018","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-02-24T19:58:56.851392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.998477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:58:56.851455Z","caller":"traceutil/trace.go:171","msg":"trace[516624442] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:786; }","duration":"519.16092ms","start":"2026-02-24T19:58:56.332274Z","end":"2026-02-24T19:58:56.851435Z","steps":["trace[516624442] 'count revisions from in-memory index tree' (duration: 518.896544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:56.851495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:56.332251Z","time spent":"519.233032ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-24T19:58:56.85149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.887024ms","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-02-24T19:58:56.851541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.994447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T19:58:56.851577Z","caller":"traceutil/trace.go:171","msg":"trace[716197144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"572.401446ms","start":"2026-02-24T19:58:56.279151Z","end":"2026-02-24T19:58:56.851553Z","steps":["trace[716197144] 'range keys from in-memory index tree' (duration: 571.734821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:56.851622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:56.279135Z","time spent":"572.474557ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T19:58:56.851621Z","caller":"traceutil/trace.go:171","msg":"trace[1390367775] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:786; }","duration":"509.13516ms","start":"2026-02-24T19:58:56.342465Z","end":"2026-02-24T19:58:56.8516Z","steps":["trace[1390367775] 'range keys from in-memory index tree' (duration: 508.771232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:56.851666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:56.342445Z","time spent":"509.211631ms","remote":"127.0.0.1:53776","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.169\" "} {"level":"warn","ts":"2026-02-24T19:58:56.851072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.065628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:58:56.851759Z","caller":"traceutil/trace.go:171","msg":"trace[1366605383] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:786; }","duration":"171.799265ms","start":"2026-02-24T19:58:56.679931Z","end":"2026-02-24T19:58:56.85173Z","steps":["trace[1366605383] 'count revisions from in-memory index tree' (duration: 170.975516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.028947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826901010414304,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T19:58:59.068576Z","caller":"traceutil/trace.go:171","msg":"trace[584548207] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"540.691016ms","start":"2026-02-24T19:58:58.527861Z","end":"2026-02-24T19:58:59.068552Z","steps":["trace[584548207] 'read index received' (duration: 540.548673ms)","trace[584548207] 'applied index is now lower than readState.Index' (duration: 141.493µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:59.068712Z","caller":"traceutil/trace.go:171","msg":"trace[942140464] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"805.124013ms","start":"2026-02-24T19:58:58.263576Z","end":"2026-02-24T19:58:59.0687Z","steps":["trace[942140464] 'process raft request' (duration: 804.810236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.068867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:58.263535Z","time spent":"805.213794ms","remote":"127.0.0.1:54018","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-02-24T19:58:59.069043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.186139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:58:59.069082Z","caller":"traceutil/trace.go:171","msg":"trace[1774270826] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:793; }","duration":"541.2649ms","start":"2026-02-24T19:58:58.527807Z","end":"2026-02-24T19:58:59.069072Z","steps":["trace[1774270826] 'agreement among raft nodes before linearized reading' (duration: 541.196217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.069113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:58.527787Z","time spent":"541.318231ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T19:58:59.069292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.035556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T19:58:59.069349Z","caller":"traceutil/trace.go:171","msg":"trace[1404691457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"184.1454ms","start":"2026-02-24T19:58:58.885188Z","end":"2026-02-24T19:58:59.069334Z","steps":["trace[1404691457] 'agreement among raft nodes before linearized reading' (duration: 184.022917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.069278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.832989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T19:58:59.069549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.116673ms","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-02-24T19:58:59.069666Z","caller":"traceutil/trace.go:171","msg":"trace[1112253684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"126.234256ms","start":"2026-02-24T19:58:58.943421Z","end":"2026-02-24T19:58:59.069656Z","steps":["trace[1112253684] 'agreement among raft nodes before linearized reading' (duration: 126.032161ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:59.069778Z","caller":"traceutil/trace.go:171","msg":"trace[136081847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"299.255479ms","start":"2026-02-24T19:58:58.770366Z","end":"2026-02-24T19:58:59.069622Z","steps":["trace[136081847] 'agreement among raft nodes before linearized reading' (duration: 298.817799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.06995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.57423ms","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-02-24T19:58:59.06999Z","caller":"traceutil/trace.go:171","msg":"trace[506370866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"125.650772ms","start":"2026-02-24T19:58:58.944329Z","end":"2026-02-24T19:58:59.06998Z","steps":["trace[506370866] 'agreement among raft nodes before linearized reading' (duration: 124.768132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.06968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.666813ms","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-02-24T19:58:59.070096Z","caller":"traceutil/trace.go:171","msg":"trace[543651323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"126.351768ms","start":"2026-02-24T19:58:58.943727Z","end":"2026-02-24T19:58:59.070079Z","steps":["trace[543651323] 'agreement among raft nodes before linearized reading' (duration: 125.454038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.470188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.843012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:58:59.470271Z","caller":"traceutil/trace.go:171","msg":"trace[1961904636] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"395.420712ms","start":"2026-02-24T19:58:59.074833Z","end":"2026-02-24T19:58:59.470254Z","steps":["trace[1961904636] 'process raft request' (duration: 191.432456ms)","trace[1961904636] 'compare' (duration: 203.613067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:59.470458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:59.074813Z","time spent":"395.560765ms","remote":"127.0.0.1:54018","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-02-24T19:58:59.595341Z","caller":"traceutil/trace.go:171","msg":"trace[1549605710] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"520.334707ms","start":"2026-02-24T19:58:59.074978Z","end":"2026-02-24T19:58:59.595312Z","steps":["trace[1549605710] 'process raft request' (duration: 518.750381ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:59.595372Z","caller":"traceutil/trace.go:171","msg":"trace[135657165] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"193.107835ms","start":"2026-02-24T19:58:59.402242Z","end":"2026-02-24T19:58:59.59535Z","steps":["trace[135657165] 'read index received' (duration: 191.499899ms)","trace[135657165] 'applied index is now lower than readState.Index' (duration: 1.606906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:58:59.595498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.241918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:58:59.59554Z","caller":"traceutil/trace.go:171","msg":"trace[1039509864] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:797; }","duration":"193.306309ms","start":"2026-02-24T19:58:59.402224Z","end":"2026-02-24T19:58:59.59553Z","steps":["trace[1039509864] 'agreement among raft nodes before linearized reading' (duration: 193.207487ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:59.595669Z","caller":"traceutil/trace.go:171","msg":"trace[6869903] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"520.50611ms","start":"2026-02-24T19:58:59.075125Z","end":"2026-02-24T19:58:59.595631Z","steps":["trace[6869903] 'process raft request' (duration: 520.179953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.595934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:59.075081Z","time spent":"520.759137ms","remote":"127.0.0.1:54018","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-02-24T19:58:59.595973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:59.074957Z","time spent":"520.46968ms","remote":"127.0.0.1:54018","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-02-24T19:58:59.596234Z","caller":"traceutil/trace.go:171","msg":"trace[1889394995] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"521.163647ms","start":"2026-02-24T19:58:59.075053Z","end":"2026-02-24T19:58:59.596216Z","steps":["trace[1889394995] 'process raft request' (duration: 520.192034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:59.596393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:59.075043Z","time spent":"521.277489ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:59:10.060018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.877461ms","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-02-24T19:59:10.060149Z","caller":"traceutil/trace.go:171","msg":"trace[1458425986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"399.048235ms","start":"2026-02-24T19:59:09.661072Z","end":"2026-02-24T19:59:10.06012Z","steps":["trace[1458425986] 'range keys from in-memory index tree' (duration: 398.697617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:10.060205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:09.661064Z","time spent":"399.130507ms","remote":"127.0.0.1:54018","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-02-24T19:59:10.060238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.65671ms","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-02-24T19:59:10.060343Z","caller":"traceutil/trace.go:171","msg":"trace[877117920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"399.780262ms","start":"2026-02-24T19:59:09.660528Z","end":"2026-02-24T19:59:10.060308Z","steps":["trace[877117920] 'range keys from in-memory index tree' (duration: 399.500476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:10.060391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:09.660518Z","time spent":"399.864275ms","remote":"127.0.0.1:54018","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-02-24T19:59:10.060493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.796739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T19:59:10.060635Z","caller":"traceutil/trace.go:171","msg":"trace[427749035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"412.014594ms","start":"2026-02-24T19:59:09.648596Z","end":"2026-02-24T19:59:10.060611Z","steps":["trace[427749035] 'range keys from in-memory index tree' (duration: 411.246437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:10.0607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:09.64857Z","time spent":"412.119206ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T19:59:10.628013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.636429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:10.628303Z","caller":"traceutil/trace.go:171","msg":"trace[1865575064] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"543.812528ms","start":"2026-02-24T19:59:10.084469Z","end":"2026-02-24T19:59:10.628282Z","steps":["trace[1865575064] 'process raft request' (duration: 543.749866ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:10.628319Z","caller":"traceutil/trace.go:171","msg":"trace[832524059] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"563.850179ms","start":"2026-02-24T19:59:10.064424Z","end":"2026-02-24T19:59:10.628275Z","steps":["trace[832524059] 'process raft request' (duration: 97.822401ms)","trace[832524059] 'compare' (duration: 465.013154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:10.628389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:10.084456Z","time spent":"543.895889ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:10.628468Z","caller":"traceutil/trace.go:171","msg":"trace[1237869852] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"562.659152ms","start":"2026-02-24T19:59:10.065795Z","end":"2026-02-24T19:59:10.628454Z","steps":["trace[1237869852] 'process raft request' (duration: 562.352646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:10.628527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:10.06577Z","time spent":"562.729973ms","remote":"127.0.0.1:54018","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-02-24T19:59:10.628514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:10.064413Z","time spent":"563.987083ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:11.252995Z","caller":"traceutil/trace.go:171","msg":"trace[1911396184] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"120.459643ms","start":"2026-02-24T19:59:11.132501Z","end":"2026-02-24T19:59:11.252961Z","steps":["trace[1911396184] 'process raft request' (duration: 120.257728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:13.270062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826901010414502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T19:59:13.70271Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06481441s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T19:59:13.70313Z","caller":"traceutil/trace.go:171","msg":"trace[718570131] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.065401314s","start":"2026-02-24T19:59:12.63771Z","end":"2026-02-24T19:59:13.703111Z","steps":["trace[718570131] 'process raft request' (duration: 1.06525501s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:13.703259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:12.63767Z","time spent":"1.065523067s","remote":"127.0.0.1:54018","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-02-24T19:59:14.224528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.621477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:14.22466Z","caller":"traceutil/trace.go:171","msg":"trace[804316535] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"1.455200908s","start":"2026-02-24T19:59:12.769439Z","end":"2026-02-24T19:59:14.22464Z","steps":["trace[804316535] 'read index received' (duration: 933.562559ms)","trace[804316535] 'applied index is now lower than readState.Index' (duration: 521.636669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:14.224747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455282389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:14.224835Z","caller":"traceutil/trace.go:171","msg":"trace[355127835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"1.455406762s","start":"2026-02-24T19:59:12.76941Z","end":"2026-02-24T19:59:14.224816Z","steps":["trace[355127835] 'agreement among raft nodes before linearized reading' (duration: 1.455273269s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.224881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:12.769392Z","time spent":"1.455480184s","remote":"127.0.0.1:53736","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-02-24T19:59:14.224888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.477819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:59:14.224896Z","caller":"traceutil/trace.go:171","msg":"trace[1059092786] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.586915919s","start":"2026-02-24T19:59:12.637946Z","end":"2026-02-24T19:59:14.224862Z","steps":["trace[1059092786] 'process raft request' (duration: 1.29984931s)","trace[1059092786] 'compare' (duration: 286.450074ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:14.224926Z","caller":"traceutil/trace.go:171","msg":"trace[239464594] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:883; }","duration":"251.549601ms","start":"2026-02-24T19:59:13.973364Z","end":"2026-02-24T19:59:14.224913Z","steps":["trace[239464594] 'agreement among raft nodes before linearized reading' (duration: 251.480809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.225081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.499204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T19:59:14.225194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.455533ms","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-02-24T19:59:14.225201Z","caller":"traceutil/trace.go:171","msg":"trace[887374752] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:883; }","duration":"587.691498ms","start":"2026-02-24T19:59:13.63749Z","end":"2026-02-24T19:59:14.225182Z","steps":["trace[887374752] 'agreement among raft nodes before linearized reading' (duration: 587.405481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.225262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.368155ms","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-02-24T19:59:14.225309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:13.637465Z","time spent":"587.832992ms","remote":"127.0.0.1:53880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-24T19:59:14.225348Z","caller":"traceutil/trace.go:171","msg":"trace[23826838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"668.493718ms","start":"2026-02-24T19:59:13.556835Z","end":"2026-02-24T19:59:14.225328Z","steps":["trace[23826838] 'agreement among raft nodes before linearized reading' (duration: 668.154091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.225397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:13.556816Z","time spent":"668.57277ms","remote":"127.0.0.1:54018","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-02-24T19:59:14.225249Z","caller":"traceutil/trace.go:171","msg":"trace[1930782173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"968.518564ms","start":"2026-02-24T19:59:13.256704Z","end":"2026-02-24T19:59:14.225222Z","steps":["trace[1930782173] 'agreement among raft nodes before linearized reading' (duration: 968.098954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.225453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:13.256686Z","time spent":"968.75038ms","remote":"127.0.0.1:54018","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-02-24T19:59:14.225586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:12.637429Z","time spent":"1.587546563s","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:14.558478Z","caller":"traceutil/trace.go:171","msg":"trace[2003263492] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"329.541936ms","start":"2026-02-24T19:59:14.228906Z","end":"2026-02-24T19:59:14.558448Z","steps":["trace[2003263492] 'process raft request' (duration: 309.317831ms)","trace[2003263492] 'compare' (duration: 20.051661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:14.558668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:14.228886Z","time spent":"329.69944ms","remote":"127.0.0.1:54018","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-02-24T19:59:14.56122Z","caller":"traceutil/trace.go:171","msg":"trace[1895086681] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"330.753234ms","start":"2026-02-24T19:59:14.230444Z","end":"2026-02-24T19:59:14.561197Z","steps":["trace[1895086681] 'process raft request' (duration: 330.5679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.561374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:14.230425Z","time spent":"330.867836ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:14.5614Z","caller":"traceutil/trace.go:171","msg":"trace[1787740545] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:917; }","duration":"326.322902ms","start":"2026-02-24T19:59:14.235056Z","end":"2026-02-24T19:59:14.561379Z","steps":["trace[1787740545] 'read index received' (duration: 303.178769ms)","trace[1787740545] 'applied index is now lower than readState.Index' (duration: 23.142863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:14.561493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.437035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:14.561523Z","caller":"traceutil/trace.go:171","msg":"trace[1431155848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"326.500336ms","start":"2026-02-24T19:59:14.235013Z","end":"2026-02-24T19:59:14.561513Z","steps":["trace[1431155848] 'agreement among raft nodes before linearized reading' (duration: 326.431324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.561552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:14.234994Z","time spent":"326.551248ms","remote":"127.0.0.1:53736","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-02-24T19:59:14.561755Z","caller":"traceutil/trace.go:171","msg":"trace[1656711026] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"328.204555ms","start":"2026-02-24T19:59:14.233537Z","end":"2026-02-24T19:59:14.561742Z","steps":["trace[1656711026] 'process raft request' (duration: 327.617092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:14.561837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:14.2335Z","time spent":"328.288227ms","remote":"127.0.0.1:54018","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-02-24T19:59:20.790797Z","caller":"traceutil/trace.go:171","msg":"trace[1677271906] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"119.671944ms","start":"2026-02-24T19:59:20.671103Z","end":"2026-02-24T19:59:20.790775Z","steps":["trace[1677271906] 'process raft request' (duration: 119.52045ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:26.845521Z","caller":"traceutil/trace.go:171","msg":"trace[1573997308] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"109.208674ms","start":"2026-02-24T19:59:26.736287Z","end":"2026-02-24T19:59:26.845495Z","steps":["trace[1573997308] 'process raft request' (duration: 109.04195ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:28.011037Z","caller":"traceutil/trace.go:171","msg":"trace[1345525740] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"256.588036ms","start":"2026-02-24T19:59:27.754424Z","end":"2026-02-24T19:59:28.011012Z","steps":["trace[1345525740] 'read index received' (duration: 256.284709ms)","trace[1345525740] 'applied index is now lower than readState.Index' (duration: 302.007µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:28.011085Z","caller":"traceutil/trace.go:171","msg":"trace[2074420983] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"501.477153ms","start":"2026-02-24T19:59:27.509575Z","end":"2026-02-24T19:59:28.011052Z","steps":["trace[2074420983] 'process raft request' (duration: 501.230858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:28.011227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.77155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:59:28.011275Z","caller":"traceutil/trace.go:171","msg":"trace[804361742] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:921; }","duration":"256.868723ms","start":"2026-02-24T19:59:27.754393Z","end":"2026-02-24T19:59:28.011261Z","steps":["trace[804361742] 'agreement among raft nodes before linearized reading' (duration: 256.769891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:28.011277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:27.509554Z","time spent":"501.625537ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:59:28.011364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.198542ms","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-02-24T19:59:28.011422Z","caller":"traceutil/trace.go:171","msg":"trace[712683072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"228.280944ms","start":"2026-02-24T19:59:27.783126Z","end":"2026-02-24T19:59:28.011407Z","steps":["trace[712683072] 'agreement among raft nodes before linearized reading' (duration: 228.0987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:28.011536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.172569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:28.01157Z","caller":"traceutil/trace.go:171","msg":"trace[782800039] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:921; }","duration":"141.231781ms","start":"2026-02-24T19:59:27.870329Z","end":"2026-02-24T19:59:28.011561Z","steps":["trace[782800039] 'agreement among raft nodes before linearized reading' (duration: 141.18017ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:28.142173Z","caller":"traceutil/trace.go:171","msg":"trace[953433445] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"127.462255ms","start":"2026-02-24T19:59:28.014683Z","end":"2026-02-24T19:59:28.142146Z","steps":["trace[953433445] 'process raft request' (duration: 126.159875ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:28.601279Z","caller":"traceutil/trace.go:171","msg":"trace[181854019] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"174.887516ms","start":"2026-02-24T19:59:28.426365Z","end":"2026-02-24T19:59:28.601252Z","steps":["trace[181854019] 'process raft request' (duration: 174.727802ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:29.012371Z","caller":"traceutil/trace.go:171","msg":"trace[1280597781] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"159.095161ms","start":"2026-02-24T19:59:28.853252Z","end":"2026-02-24T19:59:29.012347Z","steps":["trace[1280597781] 'process raft request' (duration: 158.914297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:29.978238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.924414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:29.978361Z","caller":"traceutil/trace.go:171","msg":"trace[1345944491] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"961.282877ms","start":"2026-02-24T19:59:29.017055Z","end":"2026-02-24T19:59:29.978338Z","steps":["trace[1345944491] 'process raft request' (duration: 469.154999ms)","trace[1345944491] 'compare' (duration: 491.616857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:29.978466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:29.017025Z","time spent":"961.39313ms","remote":"127.0.0.1:54018","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-02-24T19:59:30.003927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826901010414626,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T19:59:30.025646Z","caller":"traceutil/trace.go:171","msg":"trace[1943300966] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"522.360824ms","start":"2026-02-24T19:59:29.503261Z","end":"2026-02-24T19:59:30.025622Z","steps":["trace[1943300966] 'read index received' (duration: 522.147449ms)","trace[1943300966] 'applied index is now lower than readState.Index' (duration: 212.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:30.025759Z","caller":"traceutil/trace.go:171","msg":"trace[877056309] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.007981022s","start":"2026-02-24T19:59:29.017766Z","end":"2026-02-24T19:59:30.025747Z","steps":["trace[877056309] 'process raft request' (duration: 1.007709466s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:30.025875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:29.017733Z","time spent":"1.008056334s","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:59:30.025991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.644117ms","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":"info","ts":"2026-02-24T19:59:30.026066Z","caller":"traceutil/trace.go:171","msg":"trace[1044253335] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:926; }","duration":"309.76172ms","start":"2026-02-24T19:59:29.716283Z","end":"2026-02-24T19:59:30.026045Z","steps":["trace[1044253335] 'agreement among raft nodes before linearized reading' (duration: 309.628487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:30.026108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:29.716263Z","time spent":"309.835311ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T19:59:30.026245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.848821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:30.02629Z","caller":"traceutil/trace.go:171","msg":"trace[817564119] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:926; }","duration":"236.912942ms","start":"2026-02-24T19:59:29.789364Z","end":"2026-02-24T19:59:30.026277Z","steps":["trace[817564119] 'agreement among raft nodes before linearized reading' (duration: 236.841461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:30.026335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.825858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:30.026367Z","caller":"traceutil/trace.go:171","msg":"trace[256820171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"255.89505ms","start":"2026-02-24T19:59:29.770463Z","end":"2026-02-24T19:59:30.026358Z","steps":["trace[256820171] 'agreement among raft nodes before linearized reading' (duration: 255.844439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:30.026466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.212672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:30.026502Z","caller":"traceutil/trace.go:171","msg":"trace[190631177] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:926; }","duration":"523.285004ms","start":"2026-02-24T19:59:29.503205Z","end":"2026-02-24T19:59:30.02649Z","steps":["trace[190631177] 'agreement among raft nodes before linearized reading' (duration: 523.234193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:30.026531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:29.503182Z","time spent":"523.341636ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-24T19:59:30.735212Z","caller":"traceutil/trace.go:171","msg":"trace[75210273] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"125.9582ms","start":"2026-02-24T19:59:30.609237Z","end":"2026-02-24T19:59:30.735196Z","steps":["trace[75210273] 'process raft request' (duration: 125.840997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:31.523126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.470513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:31.523237Z","caller":"traceutil/trace.go:171","msg":"trace[1215892273] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"506.010927ms","start":"2026-02-24T19:59:31.017207Z","end":"2026-02-24T19:59:31.523218Z","steps":["trace[1215892273] 'read index received' (duration: 266.409812ms)","trace[1215892273] 'applied index is now lower than readState.Index' (duration: 239.599785ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:31.523311Z","caller":"traceutil/trace.go:171","msg":"trace[1739984166] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"701.091828ms","start":"2026-02-24T19:59:30.822209Z","end":"2026-02-24T19:59:31.523301Z","steps":["trace[1739984166] 'process raft request' (duration: 461.3827ms)","trace[1739984166] 'compare' (duration: 239.334809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:31.523366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:30.822191Z","time spent":"701.15207ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T19:59:31.52365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.437208ms","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-02-24T19:59:31.523738Z","caller":"traceutil/trace.go:171","msg":"trace[548225592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"506.566961ms","start":"2026-02-24T19:59:31.017158Z","end":"2026-02-24T19:59:31.523725Z","steps":["trace[548225592] 'agreement among raft nodes before linearized reading' (duration: 506.342776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:31.523792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.017141Z","time spent":"506.642862ms","remote":"127.0.0.1:54018","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-02-24T19:59:32.934927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.007629ms","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-02-24T19:59:32.935081Z","caller":"traceutil/trace.go:171","msg":"trace[1000296000] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:929; }","duration":"811.167952ms","start":"2026-02-24T19:59:32.123892Z","end":"2026-02-24T19:59:32.93506Z","steps":["trace[1000296000] 'range keys from in-memory index tree' (duration: 810.993828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:32.935477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294505868s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:32.93569Z","caller":"traceutil/trace.go:171","msg":"trace[799752145] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.408157034s","start":"2026-02-24T19:59:31.527516Z","end":"2026-02-24T19:59:32.935673Z","steps":["trace[799752145] 'process raft request' (duration: 1.408058842s)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:32.935707Z","caller":"traceutil/trace.go:171","msg":"trace[2052384451] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.791493438s","start":"2026-02-24T19:59:31.144193Z","end":"2026-02-24T19:59:32.935686Z","steps":["trace[2052384451] 'process raft request' (duration: 496.674574ms)","trace[2052384451] 'compare' (duration: 1.293995906s)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:32.935707Z","caller":"traceutil/trace.go:171","msg":"trace[965771887] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"1.412405331s","start":"2026-02-24T19:59:31.523273Z","end":"2026-02-24T19:59:32.935678Z","steps":["trace[965771887] 'read index received' (duration: 117.696729ms)","trace[965771887] 'applied index is now lower than readState.Index' (duration: 1.294706482s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:32.935756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.527496Z","time spent":"1.408227156s","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:59:32.935826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.144169Z","time spent":"1.79158608s","remote":"127.0.0.1:54018","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-02-24T19:59:32.935886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625064966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:32.93593Z","caller":"traceutil/trace.go:171","msg":"trace[116489371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"1.625154089s","start":"2026-02-24T19:59:31.310763Z","end":"2026-02-24T19:59:32.935917Z","steps":["trace[116489371] 'agreement among raft nodes before linearized reading' (duration: 1.625042556s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:32.935966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.310745Z","time spent":"1.62521346s","remote":"127.0.0.1:53736","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-02-24T19:59:32.93605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889961554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:59:32.936145Z","caller":"traceutil/trace.go:171","msg":"trace[1687460747] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:931; }","duration":"1.890083077s","start":"2026-02-24T19:59:31.046015Z","end":"2026-02-24T19:59:32.936098Z","steps":["trace[1687460747] 'agreement among raft nodes before linearized reading' (duration: 1.889964114s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:32.936207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.045991Z","time spent":"1.89020028s","remote":"127.0.0.1:54226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-24T19:59:33.436522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826901010414641,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T19:59:33.48167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017039201s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T19:59:33.770108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999754242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T19:59:33.770286Z","caller":"traceutil/trace.go:171","msg":"trace[548184906] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999989257s","start":"2026-02-24T19:59:31.770274Z","end":"2026-02-24T19:59:33.770263Z","steps":["trace[548184906] 'agreement among raft nodes before linearized reading' (duration: 1.999794873s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:33.770364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.770253Z","time spent":"2.000098159s","remote":"127.0.0.1:53750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 19:59:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T19:59:34.139122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.220748ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T19:59:34.139237Z","caller":"traceutil/trace.go:171","msg":"trace[1424031322] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"1.20343952s","start":"2026-02-24T19:59:32.935776Z","end":"2026-02-24T19:59:34.139215Z","steps":["trace[1424031322] 'read index received' (duration: 546.033148ms)","trace[1424031322] 'applied index is now lower than readState.Index' (duration: 657.404712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:34.139435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400615519s","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-02-24T19:59:34.139449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288053919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:34.139517Z","caller":"traceutil/trace.go:171","msg":"trace[1478422911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"1.400747183s","start":"2026-02-24T19:59:32.738751Z","end":"2026-02-24T19:59:34.139498Z","steps":["trace[1478422911] 'agreement among raft nodes before linearized reading' (duration: 1.400524187s)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:34.13954Z","caller":"traceutil/trace.go:171","msg":"trace[2055905160] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:931; }","duration":"1.288196983s","start":"2026-02-24T19:59:32.851323Z","end":"2026-02-24T19:59:34.13952Z","steps":["trace[2055905160] 'agreement among raft nodes before linearized reading' (duration: 1.288021518s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.139571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903134518s","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-02-24T19:59:34.139643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:32.851305Z","time spent":"1.288314375s","remote":"127.0.0.1:54178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-02-24T19:59:34.139641Z","caller":"traceutil/trace.go:171","msg":"trace[368915877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"1.90320976s","start":"2026-02-24T19:59:32.236413Z","end":"2026-02-24T19:59:34.139622Z","steps":["trace[368915877] 'agreement among raft nodes before linearized reading' (duration: 1.902867111s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.139687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:32.236365Z","time spent":"1.903313972s","remote":"127.0.0.1:54018","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-02-24T19:59:34.139731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.15606692s","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-02-24T19:59:34.139763Z","caller":"traceutil/trace.go:171","msg":"trace[1194948219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"2.156136852s","start":"2026-02-24T19:59:31.983618Z","end":"2026-02-24T19:59:34.139755Z","steps":["trace[1194948219] 'agreement among raft nodes before linearized reading' (duration: 2.155717721s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.139558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:32.738732Z","time spent":"1.400819046s","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T19:59:34.139783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110211125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-24T19:59:34.139791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:31.983593Z","time spent":"2.156190993s","remote":"127.0.0.1:54018","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-02-24T19:59:34.139827Z","caller":"traceutil/trace.go:171","msg":"trace[981008112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"2.110300507s","start":"2026-02-24T19:59:32.029515Z","end":"2026-02-24T19:59:34.139815Z","steps":["trace[981008112] 'agreement among raft nodes before linearized reading' (duration: 2.109807805s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.139957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:32.029495Z","time spent":"2.110445801s","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T19:59:34.158714Z","caller":"traceutil/trace.go:171","msg":"trace[180084939] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"384.802258ms","start":"2026-02-24T19:59:33.773897Z","end":"2026-02-24T19:59:34.158699Z","steps":["trace[180084939] 'process raft request' (duration: 384.676915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.15881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:33.77387Z","time spent":"384.891099ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T19:59:34.15907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.24214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T19:59:34.159108Z","caller":"traceutil/trace.go:171","msg":"trace[1607009384] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:932; }","duration":"922.324941ms","start":"2026-02-24T19:59:33.236775Z","end":"2026-02-24T19:59:34.1591Z","steps":["trace[1607009384] 'agreement among raft nodes before linearized reading' (duration: 922.239098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.159127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:33.236752Z","time spent":"922.370832ms","remote":"127.0.0.1:54196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T19:59:34.159149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.326479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T19:59:34.15923Z","caller":"traceutil/trace.go:171","msg":"trace[1231355981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"381.40908ms","start":"2026-02-24T19:59:33.777808Z","end":"2026-02-24T19:59:34.159217Z","steps":["trace[1231355981] 'agreement among raft nodes before linearized reading' (duration: 381.297198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:34.15926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:33.77775Z","time spent":"381.503932ms","remote":"127.0.0.1:53736","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-02-24T19:59:35.29808Z","caller":"traceutil/trace.go:171","msg":"trace[1592847175] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"354.833578ms","start":"2026-02-24T19:59:34.943208Z","end":"2026-02-24T19:59:35.298042Z","steps":["trace[1592847175] 'process raft request' (duration: 354.678135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:35.298222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:34.943196Z","time spent":"354.951711ms","remote":"127.0.0.1:54018","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-02-24T19:59:36.790589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.048356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:36.7908Z","caller":"traceutil/trace.go:171","msg":"trace[587776517] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"445.348961ms","start":"2026-02-24T19:59:36.345434Z","end":"2026-02-24T19:59:36.790783Z","steps":["trace[587776517] 'read index received' (duration: 141.936317ms)","trace[587776517] 'applied index is now lower than readState.Index' (duration: 303.411554ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:36.790889Z","caller":"traceutil/trace.go:171","msg":"trace[742238591] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"574.709129ms","start":"2026-02-24T19:59:36.216168Z","end":"2026-02-24T19:59:36.790878Z","steps":["trace[742238591] 'process raft request' (duration: 574.509164ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:36.790914Z","caller":"traceutil/trace.go:171","msg":"trace[815278721] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"574.834312ms","start":"2026-02-24T19:59:36.216044Z","end":"2026-02-24T19:59:36.790886Z","steps":["trace[815278721] 'process raft request' (duration: 271.387517ms)","trace[815278721] 'compare' (duration: 302.939194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:36.79099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:36.216162Z","time spent":"574.75065ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T19:59:36.791074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:36.21603Z","time spent":"574.973505ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:36.791194Z","caller":"traceutil/trace.go:171","msg":"trace[593026883] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"575.010976ms","start":"2026-02-24T19:59:36.216173Z","end":"2026-02-24T19:59:36.791184Z","steps":["trace[593026883] 'process raft request' (duration: 574.570826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:36.791279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:36.216166Z","time spent":"575.059937ms","remote":"127.0.0.1:54018","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-02-24T19:59:36.791543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.115419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T19:59:36.791589Z","caller":"traceutil/trace.go:171","msg":"trace[1315457330] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:941; }","duration":"446.200061ms","start":"2026-02-24T19:59:36.345378Z","end":"2026-02-24T19:59:36.791578Z","steps":["trace[1315457330] 'agreement among raft nodes before linearized reading' (duration: 446.066748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:36.791621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:36.345355Z","time spent":"446.259862ms","remote":"127.0.0.1:53776","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.169\" "} {"level":"warn","ts":"2026-02-24T19:59:37.248846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.594363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:37.248963Z","caller":"traceutil/trace.go:171","msg":"trace[1071775717] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"419.134978ms","start":"2026-02-24T19:59:36.829802Z","end":"2026-02-24T19:59:37.248937Z","steps":["trace[1071775717] 'process raft request' (duration: 302.37272ms)","trace[1071775717] 'compare' (duration: 116.42547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:37.249026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:36.829779Z","time spent":"419.218051ms","remote":"127.0.0.1:53776","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-02-24T19:59:37.622984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.905412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T19:59:37.623423Z","caller":"traceutil/trace.go:171","msg":"trace[1419782175] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"317.1689ms","start":"2026-02-24T19:59:37.306208Z","end":"2026-02-24T19:59:37.623377Z","steps":["trace[1419782175] 'process raft request' (duration: 73.507961ms)","trace[1419782175] 'compare' (duration: 242.667466ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:37.623765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:37.306192Z","time spent":"317.354395ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:59:38.129404Z","caller":"traceutil/trace.go:171","msg":"trace[693495873] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"325.566684ms","start":"2026-02-24T19:59:37.803811Z","end":"2026-02-24T19:59:38.129377Z","steps":["trace[693495873] 'read index received' (duration: 325.326778ms)","trace[693495873] 'applied index is now lower than readState.Index' (duration: 238.706µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:59:38.129431Z","caller":"traceutil/trace.go:171","msg":"trace[1357725817] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"428.235057ms","start":"2026-02-24T19:59:37.701168Z","end":"2026-02-24T19:59:38.129403Z","steps":["trace[1357725817] 'process raft request' (duration: 427.966331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:38.12955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:37.701135Z","time spent":"428.35938ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T19:59:38.129712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.921196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T19:59:38.129765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.953012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4742"} {"level":"info","ts":"2026-02-24T19:59:38.129759Z","caller":"traceutil/trace.go:171","msg":"trace[1449334630] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:944; }","duration":"102.031699ms","start":"2026-02-24T19:59:38.027716Z","end":"2026-02-24T19:59:38.129748Z","steps":["trace[1449334630] 'agreement among raft nodes before linearized reading' (duration: 101.866415ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:38.129825Z","caller":"traceutil/trace.go:171","msg":"trace[899696364] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:944; }","duration":"326.018024ms","start":"2026-02-24T19:59:37.803791Z","end":"2026-02-24T19:59:38.129809Z","steps":["trace[899696364] 'agreement among raft nodes before linearized reading' (duration: 325.704606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:38.129893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:59:37.803715Z","time spent":"326.165737ms","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4765,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-24T19:59:43.054872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.713507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T19:59:43.054913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.974375ms","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-02-24T19:59:43.054986Z","caller":"traceutil/trace.go:171","msg":"trace[863285644] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:982; }","duration":"173.893412ms","start":"2026-02-24T19:59:42.881073Z","end":"2026-02-24T19:59:43.054967Z","steps":["trace[863285644] 'count revisions from in-memory index tree' (duration: 173.602075ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:43.055017Z","caller":"traceutil/trace.go:171","msg":"trace[79606267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"237.147899ms","start":"2026-02-24T19:59:42.81785Z","end":"2026-02-24T19:59:43.054998Z","steps":["trace[79606267] 'range keys from in-memory index tree' (duration: 236.781501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:43.055111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.61984ms","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-02-24T19:59:43.055221Z","caller":"traceutil/trace.go:171","msg":"trace[1608008029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"237.745583ms","start":"2026-02-24T19:59:42.81743Z","end":"2026-02-24T19:59:43.055176Z","steps":["trace[1608008029] 'range keys from in-memory index tree' (duration: 237.405695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:43.055104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.722666ms","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-02-24T19:59:43.055433Z","caller":"traceutil/trace.go:171","msg":"trace[407782052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"239.069834ms","start":"2026-02-24T19:59:42.816338Z","end":"2026-02-24T19:59:43.055408Z","steps":["trace[407782052] 'range keys from in-memory index tree' (duration: 238.4823ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:58.125678Z","caller":"traceutil/trace.go:171","msg":"trace[1906314345] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"165.224558ms","start":"2026-02-24T19:59:57.960426Z","end":"2026-02-24T19:59:58.125651Z","steps":["trace[1906314345] 'read index received' (duration: 164.953642ms)","trace[1906314345] 'applied index is now lower than readState.Index' (duration: 269.956µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T19:59:58.125895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.451204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-24T19:59:58.12598Z","caller":"traceutil/trace.go:171","msg":"trace[903985926] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1069; }","duration":"165.580846ms","start":"2026-02-24T19:59:57.96038Z","end":"2026-02-24T19:59:58.125961Z","steps":["trace[903985926] 'agreement among raft nodes before linearized reading' (duration: 165.378722ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:59:58.125852Z","caller":"traceutil/trace.go:171","msg":"trace[803034535] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"265.952231ms","start":"2026-02-24T19:59:57.859781Z","end":"2026-02-24T19:59:58.125734Z","steps":["trace[803034535] 'process raft request' (duration: 265.678405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:01:55.910416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.214709ms","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:510"} {"level":"info","ts":"2026-02-24T20:01:55.910509Z","caller":"traceutil/trace.go:171","msg":"trace[1860150143] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1549; }","duration":"119.367032ms","start":"2026-02-24T20:01:55.79112Z","end":"2026-02-24T20:01:55.910487Z","steps":["trace[1860150143] 'range keys from in-memory index tree' (duration: 119.009523ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:01:56.318031Z","caller":"traceutil/trace.go:171","msg":"trace[1515982832] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"218.836217ms","start":"2026-02-24T20:01:56.099166Z","end":"2026-02-24T20:01:56.318002Z","steps":["trace[1515982832] 'process raft request' (duration: 195.397154ms)","trace[1515982832] 'compare' (duration: 23.083064ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:01:56.318029Z","caller":"traceutil/trace.go:171","msg":"trace[1062461879] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"218.400297ms","start":"2026-02-24T20:01:56.099598Z","end":"2026-02-24T20:01:56.317998Z","steps":["trace[1062461879] 'process raft request' (duration: 218.316935ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:20.562896Z","caller":"traceutil/trace.go:171","msg":"trace[869741883] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"137.536581ms","start":"2026-02-24T20:02:20.425328Z","end":"2026-02-24T20:02:20.562865Z","steps":["trace[869741883] 'process raft request' (duration: 137.400208ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:20.598066Z","caller":"traceutil/trace.go:171","msg":"trace[347804875] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"170.60773ms","start":"2026-02-24T20:02:20.427433Z","end":"2026-02-24T20:02:20.598041Z","steps":["trace[347804875] 'process raft request' (duration: 168.948131ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:55.636011Z","caller":"traceutil/trace.go:171","msg":"trace[1639512161] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"111.527842ms","start":"2026-02-24T20:02:55.524463Z","end":"2026-02-24T20:02:55.635991Z","steps":["trace[1639512161] 'read index received' (duration: 111.189125ms)","trace[1639512161] 'applied index is now lower than readState.Index' (duration: 337.907µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:02:55.636214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.735677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-24T20:02:55.636265Z","caller":"traceutil/trace.go:171","msg":"trace[1581788191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1824; }","duration":"111.825099ms","start":"2026-02-24T20:02:55.524428Z","end":"2026-02-24T20:02:55.636253Z","steps":["trace[1581788191] 'agreement among raft nodes before linearized reading' (duration: 111.653935ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:55.636515Z","caller":"traceutil/trace.go:171","msg":"trace[771551292] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"166.152856ms","start":"2026-02-24T20:02:55.47035Z","end":"2026-02-24T20:02:55.636503Z","steps":["trace[771551292] 'process raft request' (duration: 165.544022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:56.461441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.684997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T20:02:56.461524Z","caller":"traceutil/trace.go:171","msg":"trace[693808700] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1826; }","duration":"265.808689ms","start":"2026-02-24T20:02:56.195697Z","end":"2026-02-24T20:02:56.461505Z","steps":["trace[693808700] 'count revisions from in-memory index tree' (duration: 265.617285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:56.461582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.343877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T20:02:56.461681Z","caller":"traceutil/trace.go:171","msg":"trace[585008352] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1826; }","duration":"105.485831ms","start":"2026-02-24T20:02:56.356168Z","end":"2026-02-24T20:02:56.461654Z","steps":["trace[585008352] 'range keys from in-memory index tree' (duration: 105.134123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:56.461905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.176217ms","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":"info","ts":"2026-02-24T20:02:56.462038Z","caller":"traceutil/trace.go:171","msg":"trace[1905543665] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1826; }","duration":"437.347101ms","start":"2026-02-24T20:02:56.024676Z","end":"2026-02-24T20:02:56.462023Z","steps":["trace[1905543665] 'range keys from in-memory index tree' (duration: 436.667855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:56.462098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:02:56.024654Z","time spent":"437.409943ms","remote":"127.0.0.1:54018","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-02-24T20:03:09.526406Z","caller":"traceutil/trace.go:171","msg":"trace[750602532] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"186.279656ms","start":"2026-02-24T20:03:09.340095Z","end":"2026-02-24T20:03:09.526375Z","steps":["trace[750602532] 'read index received' (duration: 186.062471ms)","trace[750602532] 'applied index is now lower than readState.Index' (duration: 215.105µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:03:09.526764Z","caller":"traceutil/trace.go:171","msg":"trace[1705970564] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"187.544535ms","start":"2026-02-24T20:03:09.339206Z","end":"2026-02-24T20:03:09.526751Z","steps":["trace[1705970564] 'process raft request' (duration: 186.990662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:09.527029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.921001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-02-24T20:03:09.52707Z","caller":"traceutil/trace.go:171","msg":"trace[78545814] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1889; }","duration":"187.008063ms","start":"2026-02-24T20:03:09.340052Z","end":"2026-02-24T20:03:09.52706Z","steps":["trace[78545814] 'agreement among raft nodes before linearized reading' (duration: 186.917871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:09.527085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.768247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-24T20:03:09.527125Z","caller":"traceutil/trace.go:171","msg":"trace[1484545131] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1889; }","duration":"144.860079ms","start":"2026-02-24T20:03:09.382251Z","end":"2026-02-24T20:03:09.527111Z","steps":["trace[1484545131] 'agreement among raft nodes before linearized reading' (duration: 144.776787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:10.051993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.646078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-02-24T20:03:10.052086Z","caller":"traceutil/trace.go:171","msg":"trace[411016251] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:1; response_revision:1891; }","duration":"223.777491ms","start":"2026-02-24T20:03:09.828285Z","end":"2026-02-24T20:03:10.052063Z","steps":["trace[411016251] 'range keys from in-memory index tree' (duration: 223.518715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:10.052056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.085414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-02-24T20:03:10.052155Z","caller":"traceutil/trace.go:171","msg":"trace[729670775] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1891; }","duration":"226.240018ms","start":"2026-02-24T20:03:09.825893Z","end":"2026-02-24T20:03:10.052133Z","steps":["trace[729670775] 'range keys from in-memory index tree' (duration: 225.955771ms)"],"step_count":1}