{"level":"warn","ts":"2026-02-24T10:45:35.026351Z","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-24T10:45:35.026447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-24T10:45:35.026519Z","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-24T10:45:35.02653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-24T10:45:35.026545Z","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-24T10:45:35.027219Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-24T10:45:35.027288Z","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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-24T10:45:35.030271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.773206ms"} {"level":"info","ts":"2026-02-24T10:45:35.037173Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-24T10:45:35.037235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:45:35.037262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-24T10:45:35.037276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:45:35.037292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-24T10:45:35.037336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-24T10:45:35.043826Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:45:35.045211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:45:35.046324Z","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-24T10:45:35.052617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:45:35.053079Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:45:35.053183Z","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-24T10:45:35.053208Z","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-24T10:45:35.053218Z","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-24T10:45:35.059536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-24T10:45:35.05971Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-24T10:45:35.060008Z","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-24T10:45:35.060065Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-24T10:45:35.060118Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-24T10:45:35.060244Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:45:35.06027Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:45:36.037567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:45:36.037648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:45:36.03767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-24T10:45:36.037688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:45:36.037699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-24T10:45:36.037714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-24T10:45:36.037727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-24T10:45:36.040343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:45:36.04128Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:45:36.041366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:45:36.041469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:45:36.041848Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:45:36.041969Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:45:36.042007Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:45:36.042088Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:45:36.042143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:45:36.045069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-24T10:45:36.047202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-24T10:46:06.044528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265294668,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:46:06.223793Z","caller":"traceutil/trace.go:171","msg":"trace[858048706] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"679.706187ms","start":"2026-02-24T10:46:05.544058Z","end":"2026-02-24T10:46:06.223764Z","steps":["trace[858048706] 'read index received' (duration: 651.753871ms)","trace[858048706] 'applied index is now lower than readState.Index' (duration: 27.951176ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:46:06.223956Z","caller":"traceutil/trace.go:171","msg":"trace[1326630367] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"909.630424ms","start":"2026-02-24T10:46:05.314313Z","end":"2026-02-24T10:46:06.223944Z","steps":["trace[1326630367] 'process raft request' (duration: 881.553955ms)","trace[1326630367] 'compare' (duration: 27.749621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:06.224035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.754209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:06.224136Z","caller":"traceutil/trace.go:171","msg":"trace[1373771948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"567.900582ms","start":"2026-02-24T10:46:05.656215Z","end":"2026-02-24T10:46:06.224115Z","steps":["trace[1373771948] 'agreement among raft nodes before linearized reading' (duration: 567.767949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:06.224174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:05.656192Z","time spent":"567.974713ms","remote":"127.0.0.1:54726","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-24T10:46:06.224439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.91509ms","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-24T10:46:06.224476Z","caller":"traceutil/trace.go:171","msg":"trace[2019838823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"417.989402ms","start":"2026-02-24T10:46:05.806476Z","end":"2026-02-24T10:46:06.224466Z","steps":["trace[2019838823] 'agreement among raft nodes before linearized reading' (duration: 417.865059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:06.224504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:05.806455Z","time spent":"418.042364ms","remote":"127.0.0.1:54976","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-24T10:46:06.224565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:05.314294Z","time spent":"909.691396ms","remote":"127.0.0.1:54976","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-24T10:46:06.224665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.587717ms","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-24T10:46:06.224749Z","caller":"traceutil/trace.go:171","msg":"trace[435557263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"680.73117ms","start":"2026-02-24T10:46:05.543996Z","end":"2026-02-24T10:46:06.224727Z","steps":["trace[435557263] 'agreement among raft nodes before linearized reading' (duration: 680.531456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:06.224794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:05.54397Z","time spent":"680.815733ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:46:08.37211Z","caller":"traceutil/trace.go:171","msg":"trace[1242681298] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"137.759225ms","start":"2026-02-24T10:46:08.234334Z","end":"2026-02-24T10:46:08.372093Z","steps":["trace[1242681298] 'process raft request' (duration: 137.676003ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:08.427269Z","caller":"traceutil/trace.go:171","msg":"trace[379564743] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"189.951359ms","start":"2026-02-24T10:46:08.237301Z","end":"2026-02-24T10:46:08.427252Z","steps":["trace[379564743] 'process raft request' (duration: 189.806646ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:08.427488Z","caller":"traceutil/trace.go:171","msg":"trace[833819298] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"190.136844ms","start":"2026-02-24T10:46:08.237335Z","end":"2026-02-24T10:46:08.427472Z","steps":["trace[833819298] 'read index received' (duration: 134.783594ms)","trace[833819298] 'applied index is now lower than readState.Index' (duration: 55.35259ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:46:08.427547Z","caller":"traceutil/trace.go:171","msg":"trace[434686417] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"190.141744ms","start":"2026-02-24T10:46:08.237399Z","end":"2026-02-24T10:46:08.427541Z","steps":["trace[434686417] 'process raft request' (duration: 189.802456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:08.427583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.239736ms","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-24T10:46:08.42762Z","caller":"traceutil/trace.go:171","msg":"trace[1778547903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"190.325758ms","start":"2026-02-24T10:46:08.237282Z","end":"2026-02-24T10:46:08.427608Z","steps":["trace[1778547903] 'agreement among raft nodes before linearized reading' (duration: 190.250756ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:12.118513Z","caller":"traceutil/trace.go:171","msg":"trace[1413890005] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"209.916868ms","start":"2026-02-24T10:46:11.908571Z","end":"2026-02-24T10:46:12.118488Z","steps":["trace[1413890005] 'process raft request' (duration: 209.746824ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:12.624254Z","caller":"traceutil/trace.go:171","msg":"trace[1503372055] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"177.596839ms","start":"2026-02-24T10:46:12.446626Z","end":"2026-02-24T10:46:12.624222Z","steps":["trace[1503372055] 'process raft request' (duration: 123.734714ms)","trace[1503372055] 'compare' (duration: 53.682471ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:46:12.624251Z","caller":"traceutil/trace.go:171","msg":"trace[9902908] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"175.688205ms","start":"2026-02-24T10:46:12.448537Z","end":"2026-02-24T10:46:12.624225Z","steps":["trace[9902908] 'process raft request' (duration: 175.635554ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:12.624542Z","caller":"traceutil/trace.go:171","msg":"trace[559064786] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"176.262878ms","start":"2026-02-24T10:46:12.448262Z","end":"2026-02-24T10:46:12.624524Z","steps":["trace[559064786] 'read index received' (duration: 122.111847ms)","trace[559064786] 'applied index is now lower than readState.Index' (duration: 54.149411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:12.624745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.471042ms","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-24T10:46:12.624785Z","caller":"traceutil/trace.go:171","msg":"trace[1181424963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"176.563515ms","start":"2026-02-24T10:46:12.448212Z","end":"2026-02-24T10:46:12.624776Z","steps":["trace[1181424963] 'agreement among raft nodes before linearized reading' (duration: 176.37286ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:13.314348Z","caller":"traceutil/trace.go:171","msg":"trace[147204707] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"685.825981ms","start":"2026-02-24T10:46:12.628499Z","end":"2026-02-24T10:46:13.314325Z","steps":["trace[147204707] 'process raft request' (duration: 630.670686ms)","trace[147204707] 'compare' (duration: 55.015671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:13.314468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:12.62848Z","time spent":"685.930583ms","remote":"127.0.0.1:54976","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-24T10:46:14.777367Z","caller":"traceutil/trace.go:171","msg":"trace[1142973848] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"343.2937ms","start":"2026-02-24T10:46:14.43397Z","end":"2026-02-24T10:46:14.777264Z","steps":["trace[1142973848] 'process raft request' (duration: 343.018833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:14.777555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:14.433949Z","time spent":"343.502364ms","remote":"127.0.0.1:54976","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-24T10:46:14.935242Z","caller":"traceutil/trace.go:171","msg":"trace[1893890268] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"307.028027ms","start":"2026-02-24T10:46:14.628174Z","end":"2026-02-24T10:46:14.935202Z","steps":["trace[1893890268] 'read index received' (duration: 148.791893ms)","trace[1893890268] 'applied index is now lower than readState.Index' (duration: 158.234504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:14.935503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.131201ms","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-24T10:46:14.935566Z","caller":"traceutil/trace.go:171","msg":"trace[303105246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"307.231732ms","start":"2026-02-24T10:46:14.628317Z","end":"2026-02-24T10:46:14.935549Z","steps":["trace[303105246] 'agreement among raft nodes before linearized reading' (duration: 307.053668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:14.935622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:14.628307Z","time spent":"307.300084ms","remote":"127.0.0.1:54976","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-24T10:46:14.935504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.307055ms","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-24T10:46:14.935629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.961745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:14.935683Z","caller":"traceutil/trace.go:171","msg":"trace[1734816836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"307.54768ms","start":"2026-02-24T10:46:14.628113Z","end":"2026-02-24T10:46:14.935661Z","steps":["trace[1734816836] 'agreement among raft nodes before linearized reading' (duration: 307.226153ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:14.935727Z","caller":"traceutil/trace.go:171","msg":"trace[1474766837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"278.100778ms","start":"2026-02-24T10:46:14.6576Z","end":"2026-02-24T10:46:14.935701Z","steps":["trace[1474766837] 'agreement among raft nodes before linearized reading' (duration: 277.915264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:14.935735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:14.628093Z","time spent":"307.628021ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:46:20.225823Z","caller":"traceutil/trace.go:171","msg":"trace[511306226] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"141.117762ms","start":"2026-02-24T10:46:20.084679Z","end":"2026-02-24T10:46:20.225797Z","steps":["trace[511306226] 'process raft request' (duration: 140.866876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:34.459796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.95583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:34.460054Z","caller":"traceutil/trace.go:171","msg":"trace[1545350736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"801.074644ms","start":"2026-02-24T10:46:33.658795Z","end":"2026-02-24T10:46:34.459869Z","steps":["trace[1545350736] 'range keys from in-memory index tree' (duration: 800.907389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:34.460103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:33.658785Z","time spent":"801.307659ms","remote":"127.0.0.1:54726","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-24T10:46:34.460092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.310725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:34.460186Z","caller":"traceutil/trace.go:171","msg":"trace[2037848133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"931.421357ms","start":"2026-02-24T10:46:33.528724Z","end":"2026-02-24T10:46:34.460145Z","steps":["trace[2037848133] 'range keys from in-memory index tree' (duration: 931.236213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:34.460229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:33.528706Z","time spent":"931.51524ms","remote":"127.0.0.1:54710","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-24T10:46:34.951006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.354683ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:46:34.951134Z","caller":"traceutil/trace.go:171","msg":"trace[1606854712] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"293.598907ms","start":"2026-02-24T10:46:34.657514Z","end":"2026-02-24T10:46:34.951113Z","steps":["trace[1606854712] 'read index received' (duration: 55.071µs)","trace[1606854712] 'applied index is now lower than readState.Index' (duration: 293.542046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:34.951273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.74801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:34.95137Z","caller":"traceutil/trace.go:171","msg":"trace[154790954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"293.887293ms","start":"2026-02-24T10:46:34.65746Z","end":"2026-02-24T10:46:34.951347Z","steps":["trace[154790954] 'agreement among raft nodes before linearized reading' (duration: 293.74316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:34.951427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.010518ms","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-24T10:46:34.951479Z","caller":"traceutil/trace.go:171","msg":"trace[6103045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"179.096631ms","start":"2026-02-24T10:46:34.772367Z","end":"2026-02-24T10:46:34.951464Z","steps":["trace[6103045] 'agreement among raft nodes before linearized reading' (duration: 178.879046ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:35.289878Z","caller":"traceutil/trace.go:171","msg":"trace[525334340] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"333.812453ms","start":"2026-02-24T10:46:34.956034Z","end":"2026-02-24T10:46:35.289846Z","steps":["trace[525334340] 'process raft request' (duration: 333.639459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:35.290117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:34.956018Z","time spent":"334.002587ms","remote":"127.0.0.1:54976","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-24T10:46:36.062939Z","caller":"traceutil/trace.go:171","msg":"trace[1953541832] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"1.016659553s","start":"2026-02-24T10:46:35.046253Z","end":"2026-02-24T10:46:36.062912Z","steps":["trace[1953541832] 'read index received' (duration: 243.348896ms)","trace[1953541832] 'applied index is now lower than readState.Index' (duration: 773.308827ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:46:36.063057Z","caller":"traceutil/trace.go:171","msg":"trace[1682335000] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.106902715s","start":"2026-02-24T10:46:34.956124Z","end":"2026-02-24T10:46:36.063027Z","steps":["trace[1682335000] 'process raft request' (duration: 1.007081161s)","trace[1682335000] 'compare' (duration: 99.409934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:36.06316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.515969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:46:36.063208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:34.956101Z","time spent":"1.107032077s","remote":"127.0.0.1:54976","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-24T10:46:36.063232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001339337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T10:46:36.063262Z","caller":"traceutil/trace.go:171","msg":"trace[462162912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"404.642933ms","start":"2026-02-24T10:46:35.658591Z","end":"2026-02-24T10:46:36.063234Z","steps":["trace[462162912] 'agreement among raft nodes before linearized reading' (duration: 404.490639ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:36.063271Z","caller":"traceutil/trace.go:171","msg":"trace[256976770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"1.001422608s","start":"2026-02-24T10:46:35.061838Z","end":"2026-02-24T10:46:36.063261Z","steps":["trace[256976770] 'agreement among raft nodes before linearized reading' (duration: 1.001234744s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:36.063277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017010212s","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-24T10:46:36.063315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:35.658577Z","time spent":"404.726244ms","remote":"127.0.0.1:54710","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-24T10:46:36.063334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:35.061823Z","time spent":"1.001502291s","remote":"127.0.0.1:54976","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-24T10:46:36.063363Z","caller":"traceutil/trace.go:171","msg":"trace[279262057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"1.017109644s","start":"2026-02-24T10:46:35.04623Z","end":"2026-02-24T10:46:36.06334Z","steps":["trace[279262057] 'agreement among raft nodes before linearized reading' (duration: 1.016810417s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:36.063417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:35.046153Z","time spent":"1.017254987s","remote":"127.0.0.1:54976","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-24T10:46:36.063523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.152668ms","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-24T10:46:36.063553Z","caller":"traceutil/trace.go:171","msg":"trace[1464045730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"595.21731ms","start":"2026-02-24T10:46:35.468327Z","end":"2026-02-24T10:46:36.063544Z","steps":["trace[1464045730] 'agreement among raft nodes before linearized reading' (duration: 595.129688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:36.063579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:35.468309Z","time spent":"595.263611ms","remote":"127.0.0.1:54976","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-24T10:46:38.159096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265295099,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:46:38.659987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265295099,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:46:39.075108Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.77839522s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:46:39.075526Z","caller":"traceutil/trace.go:171","msg":"trace[1777078231] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"1.416498153s","start":"2026-02-24T10:46:37.659006Z","end":"2026-02-24T10:46:39.075505Z","steps":["trace[1777078231] 'read index received' (duration: 1.416389571s)","trace[1777078231] 'applied index is now lower than readState.Index' (duration: 107.352µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:46:39.07564Z","caller":"traceutil/trace.go:171","msg":"trace[1277561458] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.779008255s","start":"2026-02-24T10:46:37.296604Z","end":"2026-02-24T10:46:39.075613Z","steps":["trace[1277561458] 'process raft request' (duration: 1.778701168s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.075686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416667558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:39.07572Z","caller":"traceutil/trace.go:171","msg":"trace[679263642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"1.41679401s","start":"2026-02-24T10:46:37.658916Z","end":"2026-02-24T10:46:39.07571Z","steps":["trace[679263642] 'agreement among raft nodes before linearized reading' (duration: 1.416695868s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.075751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:37.658893Z","time spent":"1.416851762s","remote":"127.0.0.1:54710","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-24T10:46:39.075768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:37.296588Z","time spent":"1.779108937s","remote":"127.0.0.1:54976","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-24T10:46:39.542152Z","caller":"traceutil/trace.go:171","msg":"trace[714478795] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"466.527004ms","start":"2026-02-24T10:46:39.075587Z","end":"2026-02-24T10:46:39.542114Z","steps":["trace[714478795] 'read index received' (duration: 466.279098ms)","trace[714478795] 'applied index is now lower than readState.Index' (duration: 246.706µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:39.542263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.384589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:39.542278Z","caller":"traceutil/trace.go:171","msg":"trace[2107668085] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"517.967692ms","start":"2026-02-24T10:46:39.02427Z","end":"2026-02-24T10:46:39.542238Z","steps":["trace[2107668085] 'process raft request' (duration: 517.674965ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:39.542331Z","caller":"traceutil/trace.go:171","msg":"trace[2020715380] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:729; }","duration":"913.519641ms","start":"2026-02-24T10:46:38.628791Z","end":"2026-02-24T10:46:39.54231Z","steps":["trace[2020715380] 'agreement among raft nodes before linearized reading' (duration: 913.368168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.542385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:38.628774Z","time spent":"913.599234ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:46:39.542403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413519514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T10:46:39.542417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.648504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T10:46:39.54245Z","caller":"traceutil/trace.go:171","msg":"trace[690298402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"1.413594866s","start":"2026-02-24T10:46:38.12884Z","end":"2026-02-24T10:46:39.542434Z","steps":["trace[690298402] 'agreement among raft nodes before linearized reading' (duration: 1.413434922s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:39.542466Z","caller":"traceutil/trace.go:171","msg":"trace[824960360] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:729; }","duration":"496.742737ms","start":"2026-02-24T10:46:39.04571Z","end":"2026-02-24T10:46:39.542452Z","steps":["trace[824960360] 'agreement among raft nodes before linearized reading' (duration: 496.526381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.542471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:39.02424Z","time spent":"518.127025ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:46:39.542505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:39.04568Z","time spent":"496.814319ms","remote":"127.0.0.1:54758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-24T10:46:39.542562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.689204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:39.5426Z","caller":"traceutil/trace.go:171","msg":"trace[1453510903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"465.750306ms","start":"2026-02-24T10:46:39.076838Z","end":"2026-02-24T10:46:39.542588Z","steps":["trace[1453510903] 'agreement among raft nodes before linearized reading' (duration: 465.690314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.542602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475895867s","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-24T10:46:39.542636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:39.076819Z","time spent":"465.808217ms","remote":"127.0.0.1:54726","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-24T10:46:39.542667Z","caller":"traceutil/trace.go:171","msg":"trace[907354258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.475994459s","start":"2026-02-24T10:46:38.066653Z","end":"2026-02-24T10:46:39.542648Z","steps":["trace[907354258] 'agreement among raft nodes before linearized reading' (duration: 1.475802375s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.542714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:38.066636Z","time spent":"1.47606659s","remote":"127.0.0.1:54976","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-24T10:46:39.542795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414363483s","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-24T10:46:39.542829Z","caller":"traceutil/trace.go:171","msg":"trace[1021592791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"1.414435895s","start":"2026-02-24T10:46:38.128383Z","end":"2026-02-24T10:46:39.542819Z","steps":["trace[1021592791] 'agreement among raft nodes before linearized reading' (duration: 1.414332503s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.542862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:38.128366Z","time spent":"1.414489237s","remote":"127.0.0.1:54976","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-24T10:46:39.54286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.95121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:39.542906Z","caller":"traceutil/trace.go:171","msg":"trace[303491451] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:729; }","duration":"332.030812ms","start":"2026-02-24T10:46:39.210862Z","end":"2026-02-24T10:46:39.542893Z","steps":["trace[303491451] 'agreement among raft nodes before linearized reading' (duration: 331.95659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.54291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418083721s","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-24T10:46:39.542494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:38.12883Z","time spent":"1.413651217s","remote":"127.0.0.1:54976","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-24T10:46:39.542988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:39.210844Z","time spent":"332.090953ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:46:39.543004Z","caller":"traceutil/trace.go:171","msg":"trace[281575766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.418198463s","start":"2026-02-24T10:46:38.12479Z","end":"2026-02-24T10:46:39.542989Z","steps":["trace[281575766] 'agreement among raft nodes before linearized reading' (duration: 1.418024089s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:39.543057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:38.124773Z","time spent":"1.418266474s","remote":"127.0.0.1:54976","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-24T10:46:57.34934Z","caller":"traceutil/trace.go:171","msg":"trace[286812019] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"134.886071ms","start":"2026-02-24T10:46:57.214429Z","end":"2026-02-24T10:46:57.349315Z","steps":["trace[286812019] 'process raft request' (duration: 134.667196ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:57.873089Z","caller":"traceutil/trace.go:171","msg":"trace[569140608] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"131.584534ms","start":"2026-02-24T10:46:57.741479Z","end":"2026-02-24T10:46:57.873063Z","steps":["trace[569140608] 'process raft request' (duration: 131.538423ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:57.873118Z","caller":"traceutil/trace.go:171","msg":"trace[1645592702] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"132.959025ms","start":"2026-02-24T10:46:57.740125Z","end":"2026-02-24T10:46:57.873084Z","steps":["trace[1645592702] 'process raft request' (duration: 127.264653ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:57.873174Z","caller":"traceutil/trace.go:171","msg":"trace[772867646] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"132.550797ms","start":"2026-02-24T10:46:57.740594Z","end":"2026-02-24T10:46:57.873145Z","steps":["trace[772867646] 'process raft request' (duration: 132.377303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:46:59.299508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.019766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:46:59.299631Z","caller":"traceutil/trace.go:171","msg":"trace[1393031010] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"215.988629ms","start":"2026-02-24T10:46:59.083622Z","end":"2026-02-24T10:46:59.29961Z","steps":["trace[1393031010] 'process raft request' (duration: 70.754738ms)","trace[1393031010] 'compare' (duration: 144.794271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:59.944768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.62109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:46:59.944851Z","caller":"traceutil/trace.go:171","msg":"trace[1644387129] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"284.958166ms","start":"2026-02-24T10:46:59.659879Z","end":"2026-02-24T10:46:59.944838Z","steps":["trace[1644387129] 'read index received' (duration: 59.762µs)","trace[1644387129] 'applied index is now lower than readState.Index' (duration: 284.897174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:59.94492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.051207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:46:59.944939Z","caller":"traceutil/trace.go:171","msg":"trace[1322612289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"285.069308ms","start":"2026-02-24T10:46:59.659863Z","end":"2026-02-24T10:46:59.944932Z","steps":["trace[1322612289] 'agreement among raft nodes before linearized reading' (duration: 285.026857ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:59.944933Z","caller":"traceutil/trace.go:171","msg":"trace[1840338898] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"586.950687ms","start":"2026-02-24T10:46:59.357942Z","end":"2026-02-24T10:46:59.944892Z","steps":["trace[1840338898] 'process raft request' (duration: 209.945279ms)","trace[1840338898] 'compare' (duration: 376.293631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:46:59.945181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:59.357917Z","time spent":"587.138872ms","remote":"127.0.0.1:54976","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-24T10:46:59.974397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.415549ms","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-24T10:46:59.974477Z","caller":"traceutil/trace.go:171","msg":"trace[1094874736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"222.523151ms","start":"2026-02-24T10:46:59.751923Z","end":"2026-02-24T10:46:59.974447Z","steps":["trace[1094874736] 'agreement among raft nodes before linearized reading' (duration: 222.346467ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:00.459097Z","caller":"traceutil/trace.go:171","msg":"trace[1144507120] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"481.351499ms","start":"2026-02-24T10:46:59.977723Z","end":"2026-02-24T10:47:00.459074Z","steps":["trace[1144507120] 'process raft request' (duration: 481.094583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:00.45944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:59.9777Z","time spent":"481.473701ms","remote":"127.0.0.1:54976","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-24T10:47:00.521989Z","caller":"traceutil/trace.go:171","msg":"trace[1971921970] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"536.195875ms","start":"2026-02-24T10:46:59.985768Z","end":"2026-02-24T10:47:00.521964Z","steps":["trace[1971921970] 'process raft request' (duration: 536.155724ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:00.522029Z","caller":"traceutil/trace.go:171","msg":"trace[1928671099] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"542.955973ms","start":"2026-02-24T10:46:59.979037Z","end":"2026-02-24T10:47:00.521993Z","steps":["trace[1928671099] 'process raft request' (duration: 542.705647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:00.522179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:59.985744Z","time spent":"536.323849ms","remote":"127.0.0.1:54976","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-24T10:47:00.522198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:59.979018Z","time spent":"543.086606ms","remote":"127.0.0.1:54976","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-24T10:47:00.522285Z","caller":"traceutil/trace.go:171","msg":"trace[135346285] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"542.945733ms","start":"2026-02-24T10:46:59.979124Z","end":"2026-02-24T10:47:00.52207Z","steps":["trace[135346285] 'process raft request' (duration: 542.763399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:00.522494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:46:59.979117Z","time spent":"543.26001ms","remote":"127.0.0.1:54976","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-24T10:47:10.023745Z","caller":"traceutil/trace.go:171","msg":"trace[2041647031] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"113.033561ms","start":"2026-02-24T10:47:09.91069Z","end":"2026-02-24T10:47:10.023724Z","steps":["trace[2041647031] 'process raft request' (duration: 112.590121ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:10.20532Z","caller":"traceutil/trace.go:171","msg":"trace[288822823] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"120.542306ms","start":"2026-02-24T10:47:10.084752Z","end":"2026-02-24T10:47:10.205294Z","steps":["trace[288822823] 'process raft request' (duration: 65.681019ms)","trace[288822823] 'compare' (duration: 54.752495ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:10.208826Z","caller":"traceutil/trace.go:171","msg":"trace[1856718715] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"119.995074ms","start":"2026-02-24T10:47:10.088805Z","end":"2026-02-24T10:47:10.2088Z","steps":["trace[1856718715] 'process raft request' (duration: 119.862621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:12.699328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.560956ms","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-24T10:47:12.699421Z","caller":"traceutil/trace.go:171","msg":"trace[1061280395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"129.661899ms","start":"2026-02-24T10:47:12.569717Z","end":"2026-02-24T10:47:12.699379Z","steps":["trace[1061280395] 'range keys from in-memory index tree' (duration: 129.406143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:12.699337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.177065ms","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-24T10:47:12.699492Z","caller":"traceutil/trace.go:171","msg":"trace[1841933605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"192.373179ms","start":"2026-02-24T10:47:12.507104Z","end":"2026-02-24T10:47:12.699477Z","steps":["trace[1841933605] 'range keys from in-memory index tree' (duration: 192.034801ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:13.018195Z","caller":"traceutil/trace.go:171","msg":"trace[1617805358] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"314.732627ms","start":"2026-02-24T10:47:12.703441Z","end":"2026-02-24T10:47:13.018174Z","steps":["trace[1617805358] 'process raft request' (duration: 314.608295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:13.018326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:12.703431Z","time spent":"314.828969ms","remote":"127.0.0.1:54976","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-24T10:47:13.499633Z","caller":"traceutil/trace.go:171","msg":"trace[57617246] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"796.118926ms","start":"2026-02-24T10:47:12.703468Z","end":"2026-02-24T10:47:13.499586Z","steps":["trace[57617246] 'process raft request' (duration: 795.85596ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:13.499681Z","caller":"traceutil/trace.go:171","msg":"trace[1078735398] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"709.950621ms","start":"2026-02-24T10:47:12.7897Z","end":"2026-02-24T10:47:13.499651Z","steps":["trace[1078735398] 'process raft request' (duration: 709.90443ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:13.499701Z","caller":"traceutil/trace.go:171","msg":"trace[917033503] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"795.956193ms","start":"2026-02-24T10:47:12.703716Z","end":"2026-02-24T10:47:13.499672Z","steps":["trace[917033503] 'process raft request' (duration: 795.839521ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:13.499756Z","caller":"traceutil/trace.go:171","msg":"trace[1125792818] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"795.965654ms","start":"2026-02-24T10:47:12.703674Z","end":"2026-02-24T10:47:13.49964Z","steps":["trace[1125792818] 'process raft request' (duration: 795.82943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:13.499823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:12.703442Z","time spent":"796.27576ms","remote":"127.0.0.1:54976","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-24T10:47:13.499841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:12.789677Z","time spent":"710.077303ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:13.499846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:12.703704Z","time spent":"796.062966ms","remote":"127.0.0.1:54976","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-24T10:47:13.500047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:12.703666Z","time spent":"796.170208ms","remote":"127.0.0.1:54976","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-24T10:47:15.159618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265295487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:47:15.660473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265295487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:47:15.728596Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215308138s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:47:16.163357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.453896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:47:16.163456Z","caller":"traceutil/trace.go:171","msg":"trace[632664214] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"1.504616825s","start":"2026-02-24T10:47:14.658821Z","end":"2026-02-24T10:47:16.163438Z","steps":["trace[632664214] 'read index received' (duration: 1.069940074s)","trace[632664214] 'applied index is now lower than readState.Index' (duration: 434.675241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:16.163581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504749468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:16.163614Z","caller":"traceutil/trace.go:171","msg":"trace[667702999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"1.50482941s","start":"2026-02-24T10:47:14.658775Z","end":"2026-02-24T10:47:16.163604Z","steps":["trace[667702999] 'agreement among raft nodes before linearized reading' (duration: 1.504750068s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:16.163647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:14.658753Z","time spent":"1.504886231s","remote":"127.0.0.1:54710","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-24T10:47:16.164019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.180119ms","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-24T10:47:16.164058Z","caller":"traceutil/trace.go:171","msg":"trace[1852825681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"659.240691ms","start":"2026-02-24T10:47:15.504807Z","end":"2026-02-24T10:47:16.164047Z","steps":["trace[1852825681] 'agreement among raft nodes before linearized reading' (duration: 659.114648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:16.164072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.191692ms","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-24T10:47:16.164116Z","caller":"traceutil/trace.go:171","msg":"trace[746177541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"660.295735ms","start":"2026-02-24T10:47:15.503809Z","end":"2026-02-24T10:47:16.164105Z","steps":["trace[746177541] 'agreement among raft nodes before linearized reading' (duration: 660.135821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:16.164144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141730545s","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-24T10:47:16.164168Z","caller":"traceutil/trace.go:171","msg":"trace[878812871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"1.141752995s","start":"2026-02-24T10:47:15.022408Z","end":"2026-02-24T10:47:16.164161Z","steps":["trace[878812871] 'agreement among raft nodes before linearized reading' (duration: 1.14150999s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:16.164166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:15.503786Z","time spent":"660.354557ms","remote":"127.0.0.1:54976","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-24T10:47:16.164191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:15.02235Z","time spent":"1.141835528s","remote":"127.0.0.1:54976","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-24T10:47:16.164085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:15.504795Z","time spent":"659.283421ms","remote":"127.0.0.1:54976","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-24T10:47:16.164523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.690335ms","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-24T10:47:16.164554Z","caller":"traceutil/trace.go:171","msg":"trace[815147136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"660.754585ms","start":"2026-02-24T10:47:15.503791Z","end":"2026-02-24T10:47:16.164545Z","steps":["trace[815147136] 'agreement among raft nodes before linearized reading' (duration: 660.669303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:16.164583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:15.503771Z","time spent":"660.806257ms","remote":"127.0.0.1:54976","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-24T10:47:36.812687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810571265295676,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:47:37.004617Z","caller":"traceutil/trace.go:171","msg":"trace[952054628] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"692.244456ms","start":"2026-02-24T10:47:36.312345Z","end":"2026-02-24T10:47:37.00459Z","steps":["trace[952054628] 'read index received' (duration: 692.040491ms)","trace[952054628] 'applied index is now lower than readState.Index' (duration: 202.635µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:37.004751Z","caller":"traceutil/trace.go:171","msg":"trace[1813517456] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"692.694087ms","start":"2026-02-24T10:47:36.312044Z","end":"2026-02-24T10:47:37.004738Z","steps":["trace[1813517456] 'process raft request' (duration: 692.353939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.004858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.312028Z","time spent":"692.752767ms","remote":"127.0.0.1:54976","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-24T10:47:37.005058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.370559ms","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-24T10:47:37.005156Z","caller":"traceutil/trace.go:171","msg":"trace[1149885987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"689.492742ms","start":"2026-02-24T10:47:36.315642Z","end":"2026-02-24T10:47:37.005135Z","steps":["trace[1149885987] 'agreement among raft nodes before linearized reading' (duration: 689.290357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.005195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.315633Z","time spent":"689.553283ms","remote":"127.0.0.1:54976","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-24T10:47:37.005317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.513107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:37.00536Z","caller":"traceutil/trace.go:171","msg":"trace[1902130862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"346.62679ms","start":"2026-02-24T10:47:36.658719Z","end":"2026-02-24T10:47:37.005346Z","steps":["trace[1902130862] 'agreement among raft nodes before linearized reading' (duration: 346.539598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.00539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.658699Z","time spent":"346.685201ms","remote":"127.0.0.1:54710","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-24T10:47:37.005414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.940618ms","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-24T10:47:37.005445Z","caller":"traceutil/trace.go:171","msg":"trace[144063701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"614.973229ms","start":"2026-02-24T10:47:36.390463Z","end":"2026-02-24T10:47:37.005436Z","steps":["trace[144063701] 'agreement among raft nodes before linearized reading' (duration: 614.888687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.005492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.390404Z","time spent":"615.066592ms","remote":"127.0.0.1:54976","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-24T10:47:37.005647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.29673ms","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-24T10:47:37.005679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.708178ms","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-24T10:47:37.005685Z","caller":"traceutil/trace.go:171","msg":"trace[977726438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"693.352431ms","start":"2026-02-24T10:47:36.312322Z","end":"2026-02-24T10:47:37.005675Z","steps":["trace[977726438] 'agreement among raft nodes before linearized reading' (duration: 693.206108ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:37.00571Z","caller":"traceutil/trace.go:171","msg":"trace[824021702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"668.77781ms","start":"2026-02-24T10:47:36.336924Z","end":"2026-02-24T10:47:37.005702Z","steps":["trace[824021702] 'agreement among raft nodes before linearized reading' (duration: 668.684558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.005716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.312314Z","time spent":"693.396182ms","remote":"127.0.0.1:54976","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-24T10:47:37.005736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:36.336902Z","time spent":"668.829392ms","remote":"127.0.0.1:54976","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-24T10:47:37.49078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.231632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:47:37.491067Z","caller":"traceutil/trace.go:171","msg":"trace[1976458427] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"480.067733ms","start":"2026-02-24T10:47:37.010974Z","end":"2026-02-24T10:47:37.491042Z","steps":["trace[1976458427] 'process raft request' (duration: 28.491553ms)","trace[1976458427] 'compare' (duration: 451.079378ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:37.49115Z","caller":"traceutil/trace.go:171","msg":"trace[904615094] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"480.125474ms","start":"2026-02-24T10:47:37.011012Z","end":"2026-02-24T10:47:37.491137Z","steps":["trace[904615094] 'process raft request' (duration: 479.865018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.491195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:37.01096Z","time spent":"480.170475ms","remote":"127.0.0.1:54976","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-24T10:47:37.491211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:37.010941Z","time spent":"480.241586ms","remote":"127.0.0.1:54976","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-24T10:47:37.491086Z","caller":"traceutil/trace.go:171","msg":"trace[534948308] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"479.871957ms","start":"2026-02-24T10:47:37.011196Z","end":"2026-02-24T10:47:37.491068Z","steps":["trace[534948308] 'process raft request' (duration: 479.834236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.491315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:37.011188Z","time spent":"480.107994ms","remote":"127.0.0.1:54976","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-24T10:47:37.491236Z","caller":"traceutil/trace.go:171","msg":"trace[343877289] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"480.123534ms","start":"2026-02-24T10:47:37.011049Z","end":"2026-02-24T10:47:37.491172Z","steps":["trace[343877289] 'process raft request' (duration: 479.94718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:37.492445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:37.011026Z","time spent":"480.357019ms","remote":"127.0.0.1:54976","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-24T10:47:38.742851Z","caller":"traceutil/trace.go:171","msg":"trace[755772338] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"149.633019ms","start":"2026-02-24T10:47:38.593196Z","end":"2026-02-24T10:47:38.742829Z","steps":["trace[755772338] 'process raft request' (duration: 149.455775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:39.326037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.912459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:47:39.326294Z","caller":"traceutil/trace.go:171","msg":"trace[585522710] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"316.94911ms","start":"2026-02-24T10:47:39.009316Z","end":"2026-02-24T10:47:39.326265Z","steps":["trace[585522710] 'read index received' (duration: 227.874549ms)","trace[585522710] 'applied index is now lower than readState.Index' (duration: 89.072901ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:39.326481Z","caller":"traceutil/trace.go:171","msg":"trace[1477454722] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"317.219806ms","start":"2026-02-24T10:47:39.009209Z","end":"2026-02-24T10:47:39.326428Z","steps":["trace[1477454722] 'process raft request' (duration: 316.99174ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:39.326503Z","caller":"traceutil/trace.go:171","msg":"trace[1810039556] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"578.105372ms","start":"2026-02-24T10:47:38.748197Z","end":"2026-02-24T10:47:39.326302Z","steps":["trace[1810039556] 'process raft request' (duration: 134.816174ms)","trace[1810039556] 'compare' (duration: 442.645793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:39.326612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.275187ms","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-24T10:47:39.326661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.009179Z","time spent":"317.38762ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:47:39.326686Z","caller":"traceutil/trace.go:171","msg":"trace[1429827618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"317.354959ms","start":"2026-02-24T10:47:39.009309Z","end":"2026-02-24T10:47:39.326664Z","steps":["trace[1429827618] 'agreement among raft nodes before linearized reading' (duration: 317.118814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:39.326678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:38.748175Z","time spent":"578.42615ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:47:39.326726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.009244Z","time spent":"317.473311ms","remote":"127.0.0.1:54976","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-24T10:47:39.326756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.948159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T10:47:39.326817Z","caller":"traceutil/trace.go:171","msg":"trace[2112102235] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:967; }","duration":"277.047992ms","start":"2026-02-24T10:47:39.049748Z","end":"2026-02-24T10:47:39.326796Z","steps":["trace[2112102235] 'agreement among raft nodes before linearized reading' (duration: 276.808227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.299971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.156735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:47:40.300253Z","caller":"traceutil/trace.go:171","msg":"trace[936611681] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1003; }","duration":"968.431068ms","start":"2026-02-24T10:47:39.3318Z","end":"2026-02-24T10:47:40.300231Z","steps":["trace[936611681] 'read index received' (duration: 18.486381ms)","trace[936611681] 'applied index is now lower than readState.Index' (duration: 949.943367ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:40.300344Z","caller":"traceutil/trace.go:171","msg":"trace[530486759] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"969.081103ms","start":"2026-02-24T10:47:39.331214Z","end":"2026-02-24T10:47:40.300295Z","steps":["trace[530486759] 'process raft request' (duration: 614.504838ms)","trace[530486759] 'compare' (duration: 354.040652ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:40.300498Z","caller":"traceutil/trace.go:171","msg":"trace[89091262] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"965.734496ms","start":"2026-02-24T10:47:39.334729Z","end":"2026-02-24T10:47:40.300464Z","steps":["trace[89091262] 'process raft request' (duration: 965.445009ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:40.300526Z","caller":"traceutil/trace.go:171","msg":"trace[1897396244] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"969.192076ms","start":"2026-02-24T10:47:39.331308Z","end":"2026-02-24T10:47:40.3005Z","steps":["trace[1897396244] 'process raft request' (duration: 968.779996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.300528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.331192Z","time spent":"969.250907ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:47:40.300583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.334718Z","time spent":"965.826858ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:47:40.300606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.791547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-24T10:47:40.30063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.331287Z","time spent":"969.282088ms","remote":"127.0.0.1:54976","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-24T10:47:40.300655Z","caller":"traceutil/trace.go:171","msg":"trace[649549381] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:970; }","duration":"968.868048ms","start":"2026-02-24T10:47:39.331773Z","end":"2026-02-24T10:47:40.300641Z","steps":["trace[649549381] 'agreement among raft nodes before linearized reading' (duration: 968.696544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.300698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.331743Z","time spent":"968.94296ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-24T10:47:40.61793Z","caller":"traceutil/trace.go:171","msg":"trace[699479056] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.266494798s","start":"2026-02-24T10:47:39.351376Z","end":"2026-02-24T10:47:40.617871Z","steps":["trace[699479056] 'process raft request' (duration: 1.173941936s)","trace[699479056] 'compare' (duration: 92.333796ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:40.617986Z","caller":"traceutil/trace.go:171","msg":"trace[1726022210] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"302.449712ms","start":"2026-02-24T10:47:40.315487Z","end":"2026-02-24T10:47:40.617937Z","steps":["trace[1726022210] 'process raft request' (duration: 302.37517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.618177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270259636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:40.618233Z","caller":"traceutil/trace.go:171","msg":"trace[1037126919] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:972; }","duration":"1.270365838s","start":"2026-02-24T10:47:39.347853Z","end":"2026-02-24T10:47:40.618219Z","steps":["trace[1037126919] 'agreement among raft nodes before linearized reading' (duration: 1.270212264s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.618224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:40.315456Z","time spent":"302.636137ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:40.618234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.351349Z","time spent":"1.266719243s","remote":"127.0.0.1:54758","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-24T10:47:40.618277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.347843Z","time spent":"1.27042324s","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:47:40.618254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122434049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T10:47:40.618296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.568718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:40.618346Z","caller":"traceutil/trace.go:171","msg":"trace[973273380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"958.645659ms","start":"2026-02-24T10:47:39.659684Z","end":"2026-02-24T10:47:40.61833Z","steps":["trace[973273380] 'agreement among raft nodes before linearized reading' (duration: 958.545287ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:40.617996Z","caller":"traceutil/trace.go:171","msg":"trace[1748903218] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"317.654045ms","start":"2026-02-24T10:47:40.300297Z","end":"2026-02-24T10:47:40.617951Z","steps":["trace[1748903218] 'read index received' (duration: 225.112244ms)","trace[1748903218] 'applied index is now lower than readState.Index' (duration: 92.538031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:40.61839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.659666Z","time spent":"958.715381ms","remote":"127.0.0.1:54710","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-24T10:47:40.61849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123437892s","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-24T10:47:40.618522Z","caller":"traceutil/trace.go:171","msg":"trace[1044635269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"1.123522933s","start":"2026-02-24T10:47:39.494989Z","end":"2026-02-24T10:47:40.618512Z","steps":["trace[1044635269] 'agreement among raft nodes before linearized reading' (duration: 1.123400801s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:40.618346Z","caller":"traceutil/trace.go:171","msg":"trace[214585918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"1.122566651s","start":"2026-02-24T10:47:39.495757Z","end":"2026-02-24T10:47:40.618323Z","steps":["trace[214585918] 'agreement among raft nodes before linearized reading' (duration: 1.122292875s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.618571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.494932Z","time spent":"1.123630167s","remote":"127.0.0.1:54976","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-24T10:47:40.61855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122603713s","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-24T10:47:40.618597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.495736Z","time spent":"1.122852018s","remote":"127.0.0.1:54976","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-24T10:47:40.618608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239477059s","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-24T10:47:40.61862Z","caller":"traceutil/trace.go:171","msg":"trace[216029399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"1.122729096s","start":"2026-02-24T10:47:39.495873Z","end":"2026-02-24T10:47:40.618602Z","steps":["trace[216029399] 'agreement among raft nodes before linearized reading' (duration: 1.12250234s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:40.618647Z","caller":"traceutil/trace.go:171","msg":"trace[1820297835] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:972; }","duration":"1.239548071s","start":"2026-02-24T10:47:39.379084Z","end":"2026-02-24T10:47:40.618633Z","steps":["trace[1820297835] 'agreement among raft nodes before linearized reading' (duration: 1.23949315s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.618668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.49585Z","time spent":"1.122809017s","remote":"127.0.0.1:54976","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-24T10:47:40.618676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.379066Z","time spent":"1.239603012s","remote":"127.0.0.1:55030","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":"warn","ts":"2026-02-24T10:47:40.61878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123299539s","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-24T10:47:40.618855Z","caller":"traceutil/trace.go:171","msg":"trace[592244393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"1.123435032s","start":"2026-02-24T10:47:39.495402Z","end":"2026-02-24T10:47:40.618837Z","steps":["trace[592244393] 'agreement among raft nodes before linearized reading' (duration: 1.123148765s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:40.618901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:39.495381Z","time spent":"1.123510333s","remote":"127.0.0.1:54976","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-24T10:47:41.042514Z","caller":"traceutil/trace.go:171","msg":"trace[1172629755] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1010; }","duration":"394.535103ms","start":"2026-02-24T10:47:40.647963Z","end":"2026-02-24T10:47:41.042498Z","steps":["trace[1172629755] 'read index received' (duration: 393.247333ms)","trace[1172629755] 'applied index is now lower than readState.Index' (duration: 1.28651ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:41.042595Z","caller":"traceutil/trace.go:171","msg":"trace[2059306613] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"418.50122ms","start":"2026-02-24T10:47:40.624061Z","end":"2026-02-24T10:47:41.042562Z","steps":["trace[2059306613] 'process raft request' (duration: 418.110721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:41.042698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.703218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-24T10:47:41.042748Z","caller":"traceutil/trace.go:171","msg":"trace[1624632267] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:976; }","duration":"394.81551ms","start":"2026-02-24T10:47:40.647917Z","end":"2026-02-24T10:47:41.042732Z","steps":["trace[1624632267] 'agreement among raft nodes before linearized reading' (duration: 394.651497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:41.042746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:40.624043Z","time spent":"418.618324ms","remote":"127.0.0.1:54976","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-24T10:47:41.04279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:40.647895Z","time spent":"394.885892ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-24T10:47:41.042785Z","caller":"traceutil/trace.go:171","msg":"trace[415908719] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"417.565739ms","start":"2026-02-24T10:47:40.625188Z","end":"2026-02-24T10:47:41.042754Z","steps":["trace[415908719] 'process raft request' (duration: 417.253692ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:41.042322Z","caller":"traceutil/trace.go:171","msg":"trace[2041688517] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"418.88092ms","start":"2026-02-24T10:47:40.623386Z","end":"2026-02-24T10:47:41.042267Z","steps":["trace[2041688517] 'process raft request' (duration: 417.871466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:41.04298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:40.625172Z","time spent":"417.692752ms","remote":"127.0.0.1:54976","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-24T10:47:41.043123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:40.623357Z","time spent":"419.667438ms","remote":"127.0.0.1:54976","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-24T10:47:41.075998Z","caller":"traceutil/trace.go:171","msg":"trace[264923915] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"295.067751ms","start":"2026-02-24T10:47:40.780901Z","end":"2026-02-24T10:47:41.075969Z","steps":["trace[264923915] 'process raft request' (duration: 294.918117ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:42.778284Z","caller":"traceutil/trace.go:171","msg":"trace[1037008339] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"128.710033ms","start":"2026-02-24T10:47:42.649549Z","end":"2026-02-24T10:47:42.778259Z","steps":["trace[1037008339] 'read index received' (duration: 128.445796ms)","trace[1037008339] 'applied index is now lower than readState.Index' (duration: 263.147µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:42.778404Z","caller":"traceutil/trace.go:171","msg":"trace[1065528130] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"470.398907ms","start":"2026-02-24T10:47:42.307973Z","end":"2026-02-24T10:47:42.778372Z","steps":["trace[1065528130] 'process raft request' (duration: 470.0792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:42.778498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.929797ms","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-24T10:47:42.77853Z","caller":"traceutil/trace.go:171","msg":"trace[1139820573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"129.01028ms","start":"2026-02-24T10:47:42.64951Z","end":"2026-02-24T10:47:42.77852Z","steps":["trace[1139820573] 'agreement among raft nodes before linearized reading' (duration: 128.840646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:42.778544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:42.307957Z","time spent":"470.51188ms","remote":"127.0.0.1:54976","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-24T10:47:43.037493Z","caller":"traceutil/trace.go:171","msg":"trace[1951960774] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"152.063556ms","start":"2026-02-24T10:47:42.8854Z","end":"2026-02-24T10:47:43.037464Z","steps":["trace[1951960774] 'read index received' (duration: 111.38853ms)","trace[1951960774] 'applied index is now lower than readState.Index' (duration: 40.673716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:43.037662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.24082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:47:43.037705Z","caller":"traceutil/trace.go:171","msg":"trace[349794242] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:979; }","duration":"152.349722ms","start":"2026-02-24T10:47:42.885343Z","end":"2026-02-24T10:47:43.037693Z","steps":["trace[349794242] 'agreement among raft nodes before linearized reading' (duration: 152.24516ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:43.03807Z","caller":"traceutil/trace.go:171","msg":"trace[984822652] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"254.601941ms","start":"2026-02-24T10:47:42.78324Z","end":"2026-02-24T10:47:43.037842Z","steps":["trace[984822652] 'process raft request' (duration: 213.600207ms)","trace[984822652] 'compare' (duration: 40.492682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:43.835784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.563723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:47:43.835892Z","caller":"traceutil/trace.go:171","msg":"trace[1333760177] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"786.511328ms","start":"2026-02-24T10:47:43.04936Z","end":"2026-02-24T10:47:43.835871Z","steps":["trace[1333760177] 'process raft request' (duration: 268.76587ms)","trace[1333760177] 'compare' (duration: 517.42148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:43.835952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.049344Z","time spent":"786.580109ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:47:43.872404Z","caller":"traceutil/trace.go:171","msg":"trace[221856793] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1016; }","duration":"642.856908ms","start":"2026-02-24T10:47:43.22952Z","end":"2026-02-24T10:47:43.872376Z","steps":["trace[221856793] 'read index received' (duration: 88.56887ms)","trace[221856793] 'applied index is now lower than readState.Index' (duration: 554.286808ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:43.872451Z","caller":"traceutil/trace.go:171","msg":"trace[1510165795] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"822.219118ms","start":"2026-02-24T10:47:43.050178Z","end":"2026-02-24T10:47:43.872398Z","steps":["trace[1510165795] 'process raft request' (duration: 822.000533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:43.872526Z","caller":"traceutil/trace.go:171","msg":"trace[1199877978] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"821.514872ms","start":"2026-02-24T10:47:43.051001Z","end":"2026-02-24T10:47:43.872515Z","steps":["trace[1199877978] 'process raft request' (duration: 821.302827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:43.872635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.050983Z","time spent":"821.577013ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:43.87263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.050166Z","time spent":"822.363491ms","remote":"127.0.0.1:54976","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-24T10:47:43.872857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.324678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:43.872941Z","caller":"traceutil/trace.go:171","msg":"trace[213364040] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:982; }","duration":"643.450301ms","start":"2026-02-24T10:47:43.229467Z","end":"2026-02-24T10:47:43.872917Z","steps":["trace[213364040] 'agreement among raft nodes before linearized reading' (duration: 643.268837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:43.873054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.229447Z","time spent":"643.587095ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:47:44.223501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.955613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:44.22355Z","caller":"traceutil/trace.go:171","msg":"trace[296078424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"564.053775ms","start":"2026-02-24T10:47:43.659486Z","end":"2026-02-24T10:47:44.22354Z","steps":["trace[296078424] 'agreement among raft nodes before linearized reading' (duration: 563.940892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:44.223545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.381242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:47:44.223587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.65947Z","time spent":"564.109077ms","remote":"127.0.0.1:54726","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-24T10:47:44.223625Z","caller":"traceutil/trace.go:171","msg":"trace[965344706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"693.507095ms","start":"2026-02-24T10:47:43.530096Z","end":"2026-02-24T10:47:44.223603Z","steps":["trace[965344706] 'agreement among raft nodes before linearized reading' (duration: 693.350851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:44.223624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.284595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:47:44.223674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.530076Z","time spent":"693.585046ms","remote":"127.0.0.1:54710","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-24T10:47:44.22382Z","caller":"traceutil/trace.go:171","msg":"trace[871339304] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"463.938797ms","start":"2026-02-24T10:47:43.759839Z","end":"2026-02-24T10:47:44.223778Z","steps":["trace[871339304] 'process raft request' (duration: 463.339173ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:44.223365Z","caller":"traceutil/trace.go:171","msg":"trace[629065808] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"350.858758ms","start":"2026-02-24T10:47:43.872483Z","end":"2026-02-24T10:47:44.223341Z","steps":["trace[629065808] 'read index received' (duration: 350.732785ms)","trace[629065808] 'applied index is now lower than readState.Index' (duration: 125.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:44.223707Z","caller":"traceutil/trace.go:171","msg":"trace[67042208] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:983; }","duration":"759.416428ms","start":"2026-02-24T10:47:43.464271Z","end":"2026-02-24T10:47:44.223688Z","steps":["trace[67042208] 'agreement among raft nodes before linearized reading' (duration: 759.268195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:44.224213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.759811Z","time spent":"464.112192ms","remote":"127.0.0.1:54904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5382,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:44.22434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.46425Z","time spent":"759.850858ms","remote":"127.0.0.1:54878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:47:44.540213Z","caller":"traceutil/trace.go:171","msg":"trace[2114957680] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"590.359667ms","start":"2026-02-24T10:47:43.949831Z","end":"2026-02-24T10:47:44.540191Z","steps":["trace[2114957680] 'process raft request' (duration: 577.60605ms)","trace[2114957680] 'compare' (duration: 12.565882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:44.54034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:43.949801Z","time spent":"590.47441ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:45.737809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077235256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:45.737919Z","caller":"traceutil/trace.go:171","msg":"trace[782552387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.077411071s","start":"2026-02-24T10:47:44.660491Z","end":"2026-02-24T10:47:45.737902Z","steps":["trace[782552387] 'range keys from in-memory index tree' (duration: 1.077129583s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:45.737964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:44.660462Z","time spent":"1.077492932s","remote":"127.0.0.1:54710","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-24T10:47:45.773191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.083458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:47:45.773263Z","caller":"traceutil/trace.go:171","msg":"trace[2886802] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"990.338285ms","start":"2026-02-24T10:47:44.782913Z","end":"2026-02-24T10:47:45.773251Z","steps":["trace[2886802] 'read index received' (duration: 394.097183ms)","trace[2886802] 'applied index is now lower than readState.Index' (duration: 596.240142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:45.773408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.154126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-24T10:47:45.773424Z","caller":"traceutil/trace.go:171","msg":"trace[815587839] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:984; }","duration":"139.204937ms","start":"2026-02-24T10:47:45.634213Z","end":"2026-02-24T10:47:45.773418Z","steps":["trace[815587839] 'agreement among raft nodes before linearized reading' (duration: 139.109185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:45.773469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.823455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T10:47:45.773471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.53703ms","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-24T10:47:45.773516Z","caller":"traceutil/trace.go:171","msg":"trace[1113944588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"731.934417ms","start":"2026-02-24T10:47:45.041567Z","end":"2026-02-24T10:47:45.773501Z","steps":["trace[1113944588] 'agreement among raft nodes before linearized reading' (duration: 731.754743ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:45.773537Z","caller":"traceutil/trace.go:171","msg":"trace[1697520241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"990.647023ms","start":"2026-02-24T10:47:44.782873Z","end":"2026-02-24T10:47:45.77352Z","steps":["trace[1697520241] 'agreement among raft nodes before linearized reading' (duration: 990.429248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:45.773581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:45.041514Z","time spent":"732.05377ms","remote":"127.0.0.1:54976","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-24T10:47:45.773588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:44.782851Z","time spent":"990.725564ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:47:49.27601Z","caller":"traceutil/trace.go:171","msg":"trace[2044225769] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"131.15334ms","start":"2026-02-24T10:47:49.144832Z","end":"2026-02-24T10:47:49.275986Z","steps":["trace[2044225769] 'process raft request' (duration: 51.886976ms)","trace[2044225769] 'compare' (duration: 79.162651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:52.421482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.334633ms","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-24T10:47:52.421546Z","caller":"traceutil/trace.go:171","msg":"trace[2136860164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"485.432916ms","start":"2026-02-24T10:47:51.936095Z","end":"2026-02-24T10:47:52.421528Z","steps":["trace[2136860164] 'range keys from in-memory index tree' (duration: 485.148939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:52.421352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.770432ms","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-24T10:47:52.421592Z","caller":"traceutil/trace.go:171","msg":"trace[1534873151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"436.062768ms","start":"2026-02-24T10:47:51.98552Z","end":"2026-02-24T10:47:52.421583Z","steps":["trace[1534873151] 'range keys from in-memory index tree' (duration: 435.604948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:52.421592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:51.936071Z","time spent":"485.507328ms","remote":"127.0.0.1:54976","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-24T10:47:52.421637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:51.985497Z","time spent":"436.112309ms","remote":"127.0.0.1:54976","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-24T10:47:52.421736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.14906ms","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-24T10:47:52.421725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.710245ms","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-24T10:47:52.421759Z","caller":"traceutil/trace.go:171","msg":"trace[1701244244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"470.192052ms","start":"2026-02-24T10:47:51.951559Z","end":"2026-02-24T10:47:52.421751Z","steps":["trace[1701244244] 'range keys from in-memory index tree' (duration: 469.576147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:52.421783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:51.951542Z","time spent":"470.235532ms","remote":"127.0.0.1:54976","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-24T10:47:52.421793Z","caller":"traceutil/trace.go:171","msg":"trace[787617973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"569.829808ms","start":"2026-02-24T10:47:51.851946Z","end":"2026-02-24T10:47:52.421776Z","steps":["trace[787617973] 'range keys from in-memory index tree' (duration: 569.159023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:52.421837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:51.851921Z","time spent":"569.906529ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:47:55.499088Z","caller":"traceutil/trace.go:171","msg":"trace[1895616877] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"167.854603ms","start":"2026-02-24T10:47:55.331209Z","end":"2026-02-24T10:47:55.499064Z","steps":["trace[1895616877] 'process raft request' (duration: 167.618608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.270364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.143283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:49:31.270476Z","caller":"traceutil/trace.go:171","msg":"trace[369837015] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"159.60103ms","start":"2026-02-24T10:49:31.110858Z","end":"2026-02-24T10:49:31.270459Z","steps":["trace[369837015] 'read index received' (duration: 38.333213ms)","trace[369837015] 'applied index is now lower than readState.Index' (duration: 121.266577ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:31.270584Z","caller":"traceutil/trace.go:171","msg":"trace[874561742] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"178.423948ms","start":"2026-02-24T10:49:31.092093Z","end":"2026-02-24T10:49:31.270517Z","steps":["trace[874561742] 'process raft request' (duration: 57.046579ms)","trace[874561742] 'compare' (duration: 121.00869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:31.270669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.748309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T10:49:31.270668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.789504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T10:49:31.270708Z","caller":"traceutil/trace.go:171","msg":"trace[1075327838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1439; }","duration":"158.804541ms","start":"2026-02-24T10:49:31.111892Z","end":"2026-02-24T10:49:31.270697Z","steps":["trace[1075327838] 'agreement among raft nodes before linearized reading' (duration: 158.662258ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:31.270741Z","caller":"traceutil/trace.go:171","msg":"trace[876375252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"159.910497ms","start":"2026-02-24T10:49:31.110813Z","end":"2026-02-24T10:49:31.270724Z","steps":["trace[876375252] 'agreement among raft nodes before linearized reading' (duration: 159.719672ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:59.39804Z","caller":"traceutil/trace.go:171","msg":"trace[341519985] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"164.919739ms","start":"2026-02-24T10:49:59.233099Z","end":"2026-02-24T10:49:59.398018Z","steps":["trace[341519985] 'process raft request' (duration: 106.223659ms)","trace[341519985] 'compare' (duration: 58.566537ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:00.390511Z","caller":"traceutil/trace.go:171","msg":"trace[2035448136] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"104.726934ms","start":"2026-02-24T10:50:00.285757Z","end":"2026-02-24T10:50:00.390484Z","steps":["trace[2035448136] 'process raft request' (duration: 104.5676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:03.803326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.508369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:50:03.80342Z","caller":"traceutil/trace.go:171","msg":"trace[572052691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1570; }","duration":"146.644372ms","start":"2026-02-24T10:50:03.656754Z","end":"2026-02-24T10:50:03.803398Z","steps":["trace[572052691] 'range keys from in-memory index tree' (duration: 146.390686ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:46.494686Z","caller":"traceutil/trace.go:171","msg":"trace[2079082064] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"125.375506ms","start":"2026-02-24T10:50:46.369286Z","end":"2026-02-24T10:50:46.494661Z","steps":["trace[2079082064] 'process raft request' (duration: 121.530646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:48.842681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.814407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:50:48.842769Z","caller":"traceutil/trace.go:171","msg":"trace[1858669881] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1762; }","duration":"164.93885ms","start":"2026-02-24T10:50:48.67781Z","end":"2026-02-24T10:50:48.842748Z","steps":["trace[1858669881] 'count revisions from in-memory index tree' (duration: 164.672894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:56.327425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.281624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-24T10:50:56.327542Z","caller":"traceutil/trace.go:171","msg":"trace[1317220652] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1805; }","duration":"107.448758ms","start":"2026-02-24T10:50:56.220062Z","end":"2026-02-24T10:50:56.327511Z","steps":["trace[1317220652] 'range keys from in-memory index tree' (duration: 107.047889ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:57.329856Z","caller":"traceutil/trace.go:171","msg":"trace[66021429] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"170.792066ms","start":"2026-02-24T10:50:57.159026Z","end":"2026-02-24T10:50:57.329818Z","steps":["trace[66021429] 'process raft request' (duration: 84.654525ms)","trace[66021429] 'compare' (duration: 85.983607ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:59.267226Z","caller":"traceutil/trace.go:171","msg":"trace[1180219501] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1895; }","duration":"110.340545ms","start":"2026-02-24T10:50:59.156861Z","end":"2026-02-24T10:50:59.267201Z","steps":["trace[1180219501] 'read index received' (duration: 9.325377ms)","trace[1180219501] 'applied index is now lower than readState.Index' (duration: 101.014268ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:59.26741Z","caller":"traceutil/trace.go:171","msg":"trace[1587204450] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"131.99091ms","start":"2026-02-24T10:50:59.135343Z","end":"2026-02-24T10:50:59.267334Z","steps":["trace[1587204450] 'process raft request' (duration: 125.689853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.267713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.833806ms","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-24T10:50:59.267761Z","caller":"traceutil/trace.go:171","msg":"trace[1414060039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1819; }","duration":"110.923099ms","start":"2026-02-24T10:50:59.156825Z","end":"2026-02-24T10:50:59.267749Z","steps":["trace[1414060039] 'agreement among raft nodes before linearized reading' (duration: 110.502199ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:01.410225Z","caller":"traceutil/trace.go:171","msg":"trace[793411019] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"116.716184ms","start":"2026-02-24T10:51:01.293463Z","end":"2026-02-24T10:51:01.410179Z","steps":["trace[793411019] 'process raft request' (duration: 116.414277ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:01.652481Z","caller":"traceutil/trace.go:171","msg":"trace[639345936] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"220.22663ms","start":"2026-02-24T10:51:01.432206Z","end":"2026-02-24T10:51:01.652432Z","steps":["trace[639345936] 'process raft request' (duration: 219.881532ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:02.086424Z","caller":"traceutil/trace.go:171","msg":"trace[543993319] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"121.692409ms","start":"2026-02-24T10:51:01.964709Z","end":"2026-02-24T10:51:02.086402Z","steps":["trace[543993319] 'process raft request' (duration: 121.572536ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:02.197075Z","caller":"traceutil/trace.go:171","msg":"trace[1987430808] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"102.666026ms","start":"2026-02-24T10:51:02.094385Z","end":"2026-02-24T10:51:02.197052Z","steps":["trace[1987430808] 'process raft request' (duration: 22.404343ms)","trace[1987430808] 'compare' (duration: 80.097959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:12.709274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.164391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:12.709379Z","caller":"traceutil/trace.go:171","msg":"trace[1993065741] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"104.067718ms","start":"2026-02-24T10:51:12.605295Z","end":"2026-02-24T10:51:12.709362Z","steps":["trace[1993065741] 'read index received' (duration: 32.67µs)","trace[1993065741] 'applied index is now lower than readState.Index' (duration: 104.033978ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:12.709494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.178181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:12.709557Z","caller":"traceutil/trace.go:171","msg":"trace[105348042] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1882; }","duration":"104.284984ms","start":"2026-02-24T10:51:12.60526Z","end":"2026-02-24T10:51:12.709545Z","steps":["trace[105348042] 'agreement among raft nodes before linearized reading' (duration: 104.185341ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:12.709545Z","caller":"traceutil/trace.go:171","msg":"trace[74039301] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"190.102527ms","start":"2026-02-24T10:51:12.519354Z","end":"2026-02-24T10:51:12.709457Z","steps":["trace[74039301] 'process raft request' (duration: 61.669519ms)","trace[74039301] 'compare' (duration: 128.043239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:13.893308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.057924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-24T10:51:13.893391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.012083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:13.893441Z","caller":"traceutil/trace.go:171","msg":"trace[1963037036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1884; }","duration":"387.2826ms","start":"2026-02-24T10:51:13.506127Z","end":"2026-02-24T10:51:13.89341Z","steps":["trace[1963037036] 'range keys from in-memory index tree' (duration: 386.763747ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:13.893481Z","caller":"traceutil/trace.go:171","msg":"trace[2004191386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1884; }","duration":"363.163737ms","start":"2026-02-24T10:51:13.530293Z","end":"2026-02-24T10:51:13.893457Z","steps":["trace[2004191386] 'range keys from in-memory index tree' (duration: 362.921631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:13.893519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:13.506107Z","time spent":"387.395812ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:51:13.893532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:13.530271Z","time spent":"363.252408ms","remote":"127.0.0.1:54710","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-24T10:51:13.894433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.499726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:13.894503Z","caller":"traceutil/trace.go:171","msg":"trace[1307671848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1884; }","duration":"235.610209ms","start":"2026-02-24T10:51:13.658875Z","end":"2026-02-24T10:51:13.894486Z","steps":["trace[1307671848] 'range keys from in-memory index tree' (duration: 235.374664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:24.896743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.533365ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:51:24.896865Z","caller":"traceutil/trace.go:171","msg":"trace[420577272] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2018; }","duration":"368.342018ms","start":"2026-02-24T10:51:24.528504Z","end":"2026-02-24T10:51:24.896846Z","steps":["trace[420577272] 'read index received' (duration: 65.137061ms)","trace[420577272] 'applied index is now lower than readState.Index' (duration: 303.203507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:24.897022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.503611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-02-24T10:51:24.897063Z","caller":"traceutil/trace.go:171","msg":"trace[380773148] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1936; }","duration":"368.568582ms","start":"2026-02-24T10:51:24.52848Z","end":"2026-02-24T10:51:24.897049Z","steps":["trace[380773148] 'agreement among raft nodes before linearized reading' (duration: 368.47052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:24.897101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:24.528455Z","time spent":"368.638024ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":670,"request content":"key:\"/registry/clusterroles/system:discovery\" "} {"level":"warn","ts":"2026-02-24T10:51:24.897129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.034063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:24.897198Z","caller":"traceutil/trace.go:171","msg":"trace[291416986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1936; }","duration":"237.144435ms","start":"2026-02-24T10:51:24.660034Z","end":"2026-02-24T10:51:24.897179Z","steps":["trace[291416986] 'agreement among raft nodes before linearized reading' (duration: 237.043563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:24.897306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.31882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-02-24T10:51:24.897363Z","caller":"traceutil/trace.go:171","msg":"trace[1143611164] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1936; }","duration":"315.453132ms","start":"2026-02-24T10:51:24.581897Z","end":"2026-02-24T10:51:24.89735Z","steps":["trace[1143611164] 'agreement among raft nodes before linearized reading' (duration: 315.140565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:24.897516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:24.581883Z","time spent":"315.615307ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":720,"request content":"key:\"/registry/clusterrolebindings/system:basic-user\" "}