{"level":"warn","ts":"2026-02-26T23:45:21.152132Z","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-26T23:45:21.152231Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-26T23:45:21.152295Z","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-26T23:45:21.152308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-26T23:45:21.152359Z","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-26T23:45:21.153119Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-26T23:45:21.153231Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-26T23:45:21.205753Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"52.260654ms"} {"level":"info","ts":"2026-02-26T23:45:21.257132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-26T23:45:21.257314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T23:45:21.257395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-26T23:45:21.25751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T23:45:21.257524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-26T23:45:21.25787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-26T23:45:21.326659Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T23:45:21.331178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T23:45:21.333782Z","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-26T23:45:21.337172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T23:45:21.337505Z","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-26T23:45:21.33761Z","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-26T23:45:21.337623Z","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-26T23:45:21.337868Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T23:45:21.341955Z","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-26T23:45:21.342105Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-26T23:45:21.342159Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-26T23:45:21.342394Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T23:45:21.342444Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T23:45:21.343023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-26T23:45:21.343183Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-26T23:45:21.658801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T23:45:21.658862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T23:45:21.658928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-26T23:45:21.65895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-26T23:45:21.658958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-26T23:45:21.658973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-26T23:45:21.659005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-26T23:45:21.676426Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T23:45:21.676471Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T23:45:21.67676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:45:21.67775Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T23:45:21.677808Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T23:45:21.679125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-02-26T23:45:21.679251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T23:45:21.680863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T23:45:21.684426Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:45:21.684532Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:45:21.68456Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:45:55.993286Z","caller":"traceutil/trace.go:171","msg":"trace[564921795] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"120.268297ms","start":"2026-02-26T23:45:55.872992Z","end":"2026-02-26T23:45:55.99326Z","steps":["trace[564921795] 'read index received' (duration: 120.164395ms)","trace[564921795] 'applied index is now lower than readState.Index' (duration: 103.022µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:45:55.993373Z","caller":"traceutil/trace.go:171","msg":"trace[1027074518] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"202.73827ms","start":"2026-02-26T23:45:55.790609Z","end":"2026-02-26T23:45:55.993347Z","steps":["trace[1027074518] 'process raft request' (duration: 202.521445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:55.993406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.40481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:45:55.993469Z","caller":"traceutil/trace.go:171","msg":"trace[1381666211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"120.514982ms","start":"2026-02-26T23:45:55.872943Z","end":"2026-02-26T23:45:55.993458Z","steps":["trace[1381666211] 'agreement among raft nodes before linearized reading' (duration: 120.41385ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:45:57.250581Z","caller":"traceutil/trace.go:171","msg":"trace[1856361557] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"355.902984ms","start":"2026-02-26T23:45:56.894651Z","end":"2026-02-26T23:45:57.250554Z","steps":["trace[1856361557] 'process raft request' (duration: 355.737689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:57.251228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:56.894634Z","time spent":"356.018447ms","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:45:57.93788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861397656,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:45:57.970803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.360372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:45:57.970894Z","caller":"traceutil/trace.go:171","msg":"trace[398827707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"585.488895ms","start":"2026-02-26T23:45:57.385378Z","end":"2026-02-26T23:45:57.970867Z","steps":["trace[398827707] 'range keys from in-memory index tree' (duration: 585.24285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:57.970948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:57.385354Z","time spent":"585.585118ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:45:58.439008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861397656,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:45:58.495247Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10299475s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T23:45:58.495675Z","caller":"traceutil/trace.go:171","msg":"trace[891718277] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.103512071s","start":"2026-02-26T23:45:57.392147Z","end":"2026-02-26T23:45:58.495659Z","steps":["trace[891718277] 'process raft request' (duration: 1.103284386s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:58.495811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:57.39213Z","time spent":"1.103596272s","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:45:58.495958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058337582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T23:45:58.496848Z","caller":"traceutil/trace.go:171","msg":"trace[1294916629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.059255132s","start":"2026-02-26T23:45:57.437546Z","end":"2026-02-26T23:45:58.496801Z","steps":["trace[1294916629] 'agreement among raft nodes before linearized reading' (duration: 1.058264831s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:58.496968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:57.437523Z","time spent":"1.059396545s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T23:45:58.495611Z","caller":"traceutil/trace.go:171","msg":"trace[1376861882] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"1.057973325s","start":"2026-02-26T23:45:57.437602Z","end":"2026-02-26T23:45:58.495575Z","steps":["trace[1376861882] 'read index received' (duration: 1.05775938s)","trace[1376861882] 'applied index is now lower than readState.Index' (duration: 213.185µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:45:58.497358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.447009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:45:58.497402Z","caller":"traceutil/trace.go:171","msg":"trace[1153767675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"525.529461ms","start":"2026-02-26T23:45:57.971842Z","end":"2026-02-26T23:45:58.497391Z","steps":["trace[1153767675] 'agreement among raft nodes before linearized reading' (duration: 525.45279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:58.49743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:57.971825Z","time spent":"525.600093ms","remote":"127.0.0.1:50610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:45:58.497542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.921086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:45:58.497598Z","caller":"traceutil/trace.go:171","msg":"trace[1384587748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"495.996238ms","start":"2026-02-26T23:45:58.001588Z","end":"2026-02-26T23:45:58.497585Z","steps":["trace[1384587748] 'agreement among raft nodes before linearized reading' (duration: 495.901566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:58.497616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.476248ms","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-26T23:45:58.497633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:58.001579Z","time spent":"496.046899ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T23:45:58.497651Z","caller":"traceutil/trace.go:171","msg":"trace[1899717456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"500.540069ms","start":"2026-02-26T23:45:57.997102Z","end":"2026-02-26T23:45:58.497643Z","steps":["trace[1899717456] 'agreement among raft nodes before linearized reading' (duration: 500.461897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:45:58.497699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:45:57.997093Z","time spent":"500.600061ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T23:46:00.201084Z","caller":"traceutil/trace.go:171","msg":"trace[1823720595] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"175.61789ms","start":"2026-02-26T23:46:00.025431Z","end":"2026-02-26T23:46:00.201049Z","steps":["trace[1823720595] 'process raft request' (duration: 174.547176ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:17.914108Z","caller":"traceutil/trace.go:171","msg":"trace[383905049] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"548.850216ms","start":"2026-02-26T23:46:17.365229Z","end":"2026-02-26T23:46:17.914079Z","steps":["trace[383905049] 'process raft request' (duration: 548.535279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:17.914277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:17.365215Z","time spent":"548.977518ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:19.857163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723539645s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:46:19.857272Z","caller":"traceutil/trace.go:171","msg":"trace[226399698] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"1.473280596s","start":"2026-02-26T23:46:18.383975Z","end":"2026-02-26T23:46:19.857256Z","steps":["trace[226399698] 'read index received' (duration: 69.932µs)","trace[226399698] 'applied index is now lower than readState.Index' (duration: 1.473209294s)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:19.857365Z","caller":"traceutil/trace.go:171","msg":"trace[1675545355] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.844873815s","start":"2026-02-26T23:46:18.012468Z","end":"2026-02-26T23:46:19.857342Z","steps":["trace[1675545355] 'process raft request' (duration: 120.913131ms)","trace[1675545355] 'compare' (duration: 1.723028584s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:46:19.857932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:18.012444Z","time spent":"1.844948337s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:19.858012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474025393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:46:19.858089Z","caller":"traceutil/trace.go:171","msg":"trace[1249929887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"1.474147405s","start":"2026-02-26T23:46:18.383922Z","end":"2026-02-26T23:46:19.85807Z","steps":["trace[1249929887] 'agreement among raft nodes before linearized reading' (duration: 1.474030823s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:19.858106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239769057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-26T23:46:19.858127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:18.383894Z","time spent":"1.474225647s","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:46:19.858109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235603905s","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-26T23:46:19.858096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23994907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T23:46:19.858161Z","caller":"traceutil/trace.go:171","msg":"trace[866855165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.239836268s","start":"2026-02-26T23:46:18.618307Z","end":"2026-02-26T23:46:19.858143Z","steps":["trace[866855165] 'agreement among raft nodes before linearized reading' (duration: 1.239669024s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:19.858183Z","caller":"traceutil/trace.go:171","msg":"trace[576099496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"1.235722867s","start":"2026-02-26T23:46:18.62244Z","end":"2026-02-26T23:46:19.858163Z","steps":["trace[576099496] 'agreement among raft nodes before linearized reading' (duration: 1.235519803s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:19.858196Z","caller":"traceutil/trace.go:171","msg":"trace[835450424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.240081074s","start":"2026-02-26T23:46:18.618093Z","end":"2026-02-26T23:46:19.858174Z","steps":["trace[835450424] 'agreement among raft nodes before linearized reading' (duration: 1.239869349s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:19.858209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:18.6183Z","time spent":"1.239901399s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T23:46:19.858228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:18.622424Z","time spent":"1.235796909s","remote":"127.0.0.1:50892","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-26T23:46:19.858238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:18.618077Z","time spent":"1.240152425s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T23:46:20.367424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:20.868038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:21.368718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:21.867438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000506127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-26T23:46:21.867545Z","caller":"traceutil/trace.go:171","msg":"trace[1367899107] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00064813s","start":"2026-02-26T23:46:19.866876Z","end":"2026-02-26T23:46:21.867524Z","steps":["trace[1367899107] 'agreement among raft nodes before linearized reading' (duration: 2.000516917s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:21.867593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.86687Z","time spent":"2.000711561s","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 23:46:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-26T23:46:21.869563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:22.370289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:22.656732Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.792833792s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T23:46:22.869184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.179107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:46:22.869537Z","caller":"traceutil/trace.go:171","msg":"trace[752447976] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"3.005740536s","start":"2026-02-26T23:46:19.863766Z","end":"2026-02-26T23:46:22.869506Z","steps":["trace[752447976] 'process raft request' (duration: 2.79316032s)","trace[752447976] 'compare' (duration: 211.804808ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:22.869578Z","caller":"traceutil/trace.go:171","msg":"trace[374646705] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"3.005785457s","start":"2026-02-26T23:46:19.863768Z","end":"2026-02-26T23:46:22.869554Z","steps":["trace[374646705] 'process raft request' (duration: 3.005524971s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:22.869584Z","caller":"traceutil/trace.go:171","msg":"trace[1622352464] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"3.005705145s","start":"2026-02-26T23:46:19.86386Z","end":"2026-02-26T23:46:22.869565Z","steps":["trace[1622352464] 'process raft request' (duration: 3.005535752s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:22.869667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.863745Z","time spent":"3.005878719s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:22.869678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.863745Z","time spent":"3.005855698s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:22.869689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.86385Z","time spent":"3.005781057s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:46:22.92835Z","caller":"traceutil/trace.go:171","msg":"trace[144979062] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:739; }","duration":"3.061417406s","start":"2026-02-26T23:46:19.866893Z","end":"2026-02-26T23:46:22.92831Z","steps":["trace[144979062] 'read index received' (duration: 2.790034211s)","trace[144979062] 'applied index is now lower than readState.Index' (duration: 271.381785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:46:22.928564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.395251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:46:22.928611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.010835779s","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-26T23:46:22.928645Z","caller":"traceutil/trace.go:171","msg":"trace[645342179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"3.010903861s","start":"2026-02-26T23:46:19.917731Z","end":"2026-02-26T23:46:22.928634Z","steps":["trace[645342179] 'agreement among raft nodes before linearized reading' (duration: 3.010736597s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:22.928646Z","caller":"traceutil/trace.go:171","msg":"trace[683726980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"221.501113ms","start":"2026-02-26T23:46:22.707114Z","end":"2026-02-26T23:46:22.928615Z","steps":["trace[683726980] 'agreement among raft nodes before linearized reading' (duration: 221.398431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:22.928637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060642033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:46:22.92871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.917717Z","time spent":"3.010953971s","remote":"127.0.0.1:50892","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-26T23:46:22.928715Z","caller":"traceutil/trace.go:171","msg":"trace[411496539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"1.060764536s","start":"2026-02-26T23:46:21.867934Z","end":"2026-02-26T23:46:22.928698Z","steps":["trace[411496539] 'agreement among raft nodes before linearized reading' (duration: 1.060632823s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:22.928749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:21.867921Z","time spent":"1.060821537s","remote":"127.0.0.1:50610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:46:22.928787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.987946224s","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-26T23:46:22.92885Z","caller":"traceutil/trace.go:171","msg":"trace[332509991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"2.988045026s","start":"2026-02-26T23:46:19.940792Z","end":"2026-02-26T23:46:22.928837Z","steps":["trace[332509991] 'agreement among raft nodes before linearized reading' (duration: 2.987844012s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:22.928898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:19.940775Z","time spent":"2.988114897s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T23:46:22.929014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.090436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:46:22.929093Z","caller":"traceutil/trace.go:171","msg":"trace[947743071] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:717; }","duration":"403.163807ms","start":"2026-02-26T23:46:22.525874Z","end":"2026-02-26T23:46:22.929038Z","steps":["trace[947743071] 'agreement among raft nodes before linearized reading' (duration: 402.595415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:22.929146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:22.525843Z","time spent":"403.28415ms","remote":"127.0.0.1:50998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:46:23.789876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.268202ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:46:23.789972Z","caller":"traceutil/trace.go:171","msg":"trace[1126600632] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:718; }","duration":"667.378954ms","start":"2026-02-26T23:46:23.122576Z","end":"2026-02-26T23:46:23.789955Z","steps":["trace[1126600632] 'range keys from in-memory index tree' (duration: 667.253052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:23.790782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.994278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:46:23.79116Z","caller":"traceutil/trace.go:171","msg":"trace[1841634507] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"858.349864ms","start":"2026-02-26T23:46:22.932799Z","end":"2026-02-26T23:46:23.791149Z","steps":["trace[1841634507] 'process raft request' (duration: 79.908976ms)","trace[1841634507] 'compare' (duration: 777.449836ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:23.791236Z","caller":"traceutil/trace.go:171","msg":"trace[1030955443] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:743; }","duration":"854.801886ms","start":"2026-02-26T23:46:22.936412Z","end":"2026-02-26T23:46:23.791213Z","steps":["trace[1030955443] 'read index received' (duration: 23.814356ms)","trace[1030955443] 'applied index is now lower than readState.Index' (duration: 830.98611ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:23.791339Z","caller":"traceutil/trace.go:171","msg":"trace[1262163489] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"858.433276ms","start":"2026-02-26T23:46:22.932896Z","end":"2026-02-26T23:46:23.791329Z","steps":["trace[1262163489] 'process raft request' (duration: 857.978156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:23.791404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:22.932889Z","time spent":"858.480697ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:46:23.791532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.855798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T23:46:23.791608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.202374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:46:23.791611Z","caller":"traceutil/trace.go:171","msg":"trace[1905551813] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:720; }","duration":"291.973801ms","start":"2026-02-26T23:46:23.499618Z","end":"2026-02-26T23:46:23.791592Z","steps":["trace[1905551813] 'agreement among raft nodes before linearized reading' (duration: 291.792857ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:23.791626Z","caller":"traceutil/trace.go:171","msg":"trace[1132012422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"855.238174ms","start":"2026-02-26T23:46:22.936382Z","end":"2026-02-26T23:46:23.79162Z","steps":["trace[1132012422] 'agreement among raft nodes before linearized reading' (duration: 855.209464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:23.791647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:22.936372Z","time spent":"855.271406ms","remote":"127.0.0.1:50610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:46:23.791564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:22.932788Z","time spent":"858.406495ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:46:25.218384Z","caller":"traceutil/trace.go:171","msg":"trace[1975800319] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"312.553484ms","start":"2026-02-26T23:46:24.90581Z","end":"2026-02-26T23:46:25.218364Z","steps":["trace[1975800319] 'read index received' (duration: 35.993324ms)","trace[1975800319] 'applied index is now lower than readState.Index' (duration: 276.55923ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:25.218422Z","caller":"traceutil/trace.go:171","msg":"trace[1518226256] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"340.310168ms","start":"2026-02-26T23:46:24.878078Z","end":"2026-02-26T23:46:25.218388Z","steps":["trace[1518226256] 'process raft request' (duration: 327.822892ms)","trace[1518226256] 'compare' (duration: 12.338983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:46:25.218673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:24.878066Z","time spent":"340.546433ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:25.218682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.371542ms","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-26T23:46:25.218672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.848891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T23:46:25.21874Z","caller":"traceutil/trace.go:171","msg":"trace[629703121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"255.457624ms","start":"2026-02-26T23:46:24.963263Z","end":"2026-02-26T23:46:25.218721Z","steps":["trace[629703121] 'agreement among raft nodes before linearized reading' (duration: 255.335662ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:25.218771Z","caller":"traceutil/trace.go:171","msg":"trace[948497188] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:724; }","duration":"312.952793ms","start":"2026-02-26T23:46:24.905802Z","end":"2026-02-26T23:46:25.218755Z","steps":["trace[948497188] 'agreement among raft nodes before linearized reading' (duration: 312.716878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:25.218824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:24.905777Z","time spent":"313.033465ms","remote":"127.0.0.1:50654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-26T23:46:25.21885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.094453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7c96b6546b-hkkk4.1897f0a631f19c52\" ","response":"range_response_count:1 size:826"} {"level":"warn","ts":"2026-02-26T23:46:25.219023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.958592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:46:25.219117Z","caller":"traceutil/trace.go:171","msg":"trace[67942442] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:724; }","duration":"159.142875ms","start":"2026-02-26T23:46:25.059957Z","end":"2026-02-26T23:46:25.2191Z","steps":["trace[67942442] 'agreement among raft nodes before linearized reading' (duration: 158.785188ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:25.219141Z","caller":"traceutil/trace.go:171","msg":"trace[1710202982] range","detail":"{range_begin:/registry/events/kube-system/coredns-7c96b6546b-hkkk4.1897f0a631f19c52; range_end:; response_count:1; response_revision:724; }","duration":"230.369079ms","start":"2026-02-26T23:46:24.988726Z","end":"2026-02-26T23:46:25.219095Z","steps":["trace[1710202982] 'agreement among raft nodes before linearized reading' (duration: 230.042762ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:25.218519Z","caller":"traceutil/trace.go:171","msg":"trace[24825789] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"339.736576ms","start":"2026-02-26T23:46:24.878773Z","end":"2026-02-26T23:46:25.218509Z","steps":["trace[24825789] 'process raft request' (duration: 339.558412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:25.219569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:24.878767Z","time spent":"340.702928ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:46:41.144506Z","caller":"traceutil/trace.go:171","msg":"trace[1751508682] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"128.903523ms","start":"2026-02-26T23:46:41.015572Z","end":"2026-02-26T23:46:41.144475Z","steps":["trace[1751508682] 'process raft request' (duration: 128.727029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:41.516621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.492974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T23:46:41.516659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.461647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:46:41.516733Z","caller":"traceutil/trace.go:171","msg":"trace[667777098] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:774; }","duration":"186.640877ms","start":"2026-02-26T23:46:41.33007Z","end":"2026-02-26T23:46:41.51671Z","steps":["trace[667777098] 'count revisions from in-memory index tree' (duration: 186.310358ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:41.516759Z","caller":"traceutil/trace.go:171","msg":"trace[528238094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"208.61153ms","start":"2026-02-26T23:46:41.308126Z","end":"2026-02-26T23:46:41.516737Z","steps":["trace[528238094] 'range keys from in-memory index tree' (duration: 208.343204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:41.516861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.771825ms","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-26T23:46:41.516968Z","caller":"traceutil/trace.go:171","msg":"trace[1753578839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"179.915108ms","start":"2026-02-26T23:46:41.337026Z","end":"2026-02-26T23:46:41.516941Z","steps":["trace[1753578839] 'range keys from in-memory index tree' (duration: 179.533659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:41.517298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.730579ms","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-26T23:46:41.517394Z","caller":"traceutil/trace.go:171","msg":"trace[1854631214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"211.876022ms","start":"2026-02-26T23:46:41.305493Z","end":"2026-02-26T23:46:41.517369Z","steps":["trace[1854631214] 'range keys from in-memory index tree' (duration: 210.799849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:44.062606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T23:46:44.074249Z","caller":"traceutil/trace.go:171","msg":"trace[1431987161] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"512.237468ms","start":"2026-02-26T23:46:43.561986Z","end":"2026-02-26T23:46:44.074223Z","steps":["trace[1431987161] 'read index received' (duration: 511.950121ms)","trace[1431987161] 'applied index is now lower than readState.Index' (duration: 286.417µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:44.07437Z","caller":"traceutil/trace.go:171","msg":"trace[910499297] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"921.118056ms","start":"2026-02-26T23:46:43.153236Z","end":"2026-02-26T23:46:44.074354Z","steps":["trace[910499297] 'process raft request' (duration: 920.766959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:44.074482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:43.153221Z","time spent":"921.177787ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:44.074748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.622971ms","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-26T23:46:44.074785Z","caller":"traceutil/trace.go:171","msg":"trace[639288702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"475.670632ms","start":"2026-02-26T23:46:43.599104Z","end":"2026-02-26T23:46:44.074775Z","steps":["trace[639288702] 'agreement among raft nodes before linearized reading' (duration: 475.514808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:44.074757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.639513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-26T23:46:44.07482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:43.5991Z","time spent":"475.713462ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T23:46:44.074883Z","caller":"traceutil/trace.go:171","msg":"trace[831112093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"476.814616ms","start":"2026-02-26T23:46:43.598037Z","end":"2026-02-26T23:46:44.074852Z","steps":["trace[831112093] 'agreement among raft nodes before linearized reading' (duration: 476.408537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:44.074946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:43.598027Z","time spent":"476.907728ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T23:46:44.07622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.981697ms","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-26T23:46:44.077032Z","caller":"traceutil/trace.go:171","msg":"trace[203011340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"515.012839ms","start":"2026-02-26T23:46:43.56193Z","end":"2026-02-26T23:46:44.076942Z","steps":["trace[203011340] 'agreement among raft nodes before linearized reading' (duration: 512.687287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:44.077489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:43.561846Z","time spent":"515.391237ms","remote":"127.0.0.1:50892","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-26T23:46:44.29236Z","caller":"traceutil/trace.go:171","msg":"trace[1319712104] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"209.549711ms","start":"2026-02-26T23:46:44.082757Z","end":"2026-02-26T23:46:44.292307Z","steps":["trace[1319712104] 'process raft request' (duration: 188.888616ms)","trace[1319712104] 'compare' (duration: 20.520932ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:44.347199Z","caller":"traceutil/trace.go:171","msg":"trace[577948404] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"264.316859ms","start":"2026-02-26T23:46:44.082835Z","end":"2026-02-26T23:46:44.347152Z","steps":["trace[577948404] 'process raft request' (duration: 264.075434ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:44.347223Z","caller":"traceutil/trace.go:171","msg":"trace[1582573268] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"264.34706ms","start":"2026-02-26T23:46:44.08285Z","end":"2026-02-26T23:46:44.347197Z","steps":["trace[1582573268] 'process raft request' (duration: 264.220097ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:46:44.347402Z","caller":"traceutil/trace.go:171","msg":"trace[2074987105] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"263.113163ms","start":"2026-02-26T23:46:44.084263Z","end":"2026-02-26T23:46:44.347376Z","steps":["trace[2074987105] 'process raft request' (duration: 262.844906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:55.886827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:56.387522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582862861398396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:46:56.749913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.656605017s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T23:46:56.750206Z","caller":"traceutil/trace.go:171","msg":"trace[32310600] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"1.364042515s","start":"2026-02-26T23:46:55.386143Z","end":"2026-02-26T23:46:56.750186Z","steps":["trace[32310600] 'read index received' (duration: 1.363917082s)","trace[32310600] 'applied index is now lower than readState.Index' (duration: 123.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:46:56.750386Z","caller":"traceutil/trace.go:171","msg":"trace[838332635] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"1.657121459s","start":"2026-02-26T23:46:55.093231Z","end":"2026-02-26T23:46:56.750352Z","steps":["trace[838332635] 'process raft request' (duration: 1.656836953s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.750397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364229489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:46:56.750479Z","caller":"traceutil/trace.go:171","msg":"trace[1259792383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"1.364322651s","start":"2026-02-26T23:46:55.386135Z","end":"2026-02-26T23:46:56.750457Z","steps":["trace[1259792383] 'agreement among raft nodes before linearized reading' (duration: 1.364177537s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.750519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:55.386049Z","time spent":"1.364462104s","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:46:56.750528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:55.093215Z","time spent":"1.657229071s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:46:56.750577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.93583ms","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-26T23:46:56.750601Z","caller":"traceutil/trace.go:171","msg":"trace[668175659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"350.990431ms","start":"2026-02-26T23:46:56.399603Z","end":"2026-02-26T23:46:56.750593Z","steps":["trace[668175659] 'agreement among raft nodes before linearized reading' (duration: 350.872348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.750619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.399592Z","time spent":"351.022162ms","remote":"127.0.0.1:50892","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-26T23:46:56.750591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.325384ms","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-26T23:46:56.750675Z","caller":"traceutil/trace.go:171","msg":"trace[1633337027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:864; }","duration":"582.454586ms","start":"2026-02-26T23:46:56.168199Z","end":"2026-02-26T23:46:56.750654Z","steps":["trace[1633337027] 'agreement among raft nodes before linearized reading' (duration: 582.20441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.750723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.168182Z","time spent":"582.531998ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T23:46:56.75082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.15886ms","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-26T23:46:56.750836Z","caller":"traceutil/trace.go:171","msg":"trace[1995755680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:864; }","duration":"344.194362ms","start":"2026-02-26T23:46:56.406636Z","end":"2026-02-26T23:46:56.750831Z","steps":["trace[1995755680] 'agreement among raft nodes before linearized reading' (duration: 344.153061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.75085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.406624Z","time spent":"344.223012ms","remote":"127.0.0.1:50892","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-26T23:46:56.750905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.38107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:46:56.75094Z","caller":"traceutil/trace.go:171","msg":"trace[2121434436] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:864; }","duration":"575.453111ms","start":"2026-02-26T23:46:56.175476Z","end":"2026-02-26T23:46:56.750929Z","steps":["trace[2121434436] 'agreement among raft nodes before linearized reading' (duration: 575.388119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.750968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.175457Z","time spent":"575.504103ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:46:56.750983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.880245ms","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-26T23:46:56.75097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.227046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:46:56.750999Z","caller":"traceutil/trace.go:171","msg":"trace[311107006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"353.922035ms","start":"2026-02-26T23:46:56.397073Z","end":"2026-02-26T23:46:56.750995Z","steps":["trace[311107006] 'agreement among raft nodes before linearized reading' (duration: 353.879594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.751016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.397058Z","time spent":"353.954757ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T23:46:56.751019Z","caller":"traceutil/trace.go:171","msg":"trace[1886062210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"356.311438ms","start":"2026-02-26T23:46:56.394694Z","end":"2026-02-26T23:46:56.751006Z","steps":["trace[1886062210] 'agreement among raft nodes before linearized reading' (duration: 356.143875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:46:56.751056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:46:56.394673Z","time spent":"356.374489ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T23:47:00.95248Z","caller":"traceutil/trace.go:171","msg":"trace[770656247] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"163.513286ms","start":"2026-02-26T23:47:00.788941Z","end":"2026-02-26T23:47:00.952454Z","steps":["trace[770656247] 'read index received' (duration: 163.304052ms)","trace[770656247] 'applied index is now lower than readState.Index' (duration: 207.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:00.952549Z","caller":"traceutil/trace.go:171","msg":"trace[1722554193] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"164.56234ms","start":"2026-02-26T23:47:00.787974Z","end":"2026-02-26T23:47:00.952536Z","steps":["trace[1722554193] 'process raft request' (duration: 164.261074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:00.952773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.808793ms","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-26T23:47:00.952881Z","caller":"traceutil/trace.go:171","msg":"trace[1293779875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"163.948855ms","start":"2026-02-26T23:47:00.788906Z","end":"2026-02-26T23:47:00.952855Z","steps":["trace[1293779875] 'agreement among raft nodes before linearized reading' (duration: 163.678039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:00.952933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.488419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:47:00.953036Z","caller":"traceutil/trace.go:171","msg":"trace[1168839193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"160.623553ms","start":"2026-02-26T23:47:00.792391Z","end":"2026-02-26T23:47:00.953015Z","steps":["trace[1168839193] 'agreement among raft nodes before linearized reading' (duration: 160.345927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:00.953089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.289177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T23:47:00.953137Z","caller":"traceutil/trace.go:171","msg":"trace[660729448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"161.365159ms","start":"2026-02-26T23:47:00.791757Z","end":"2026-02-26T23:47:00.953122Z","steps":["trace[660729448] 'agreement among raft nodes before linearized reading' (duration: 161.021511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:00.953175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.564997ms","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-26T23:47:00.953235Z","caller":"traceutil/trace.go:171","msg":"trace[623992658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:876; }","duration":"163.673569ms","start":"2026-02-26T23:47:00.789552Z","end":"2026-02-26T23:47:00.953225Z","steps":["trace[623992658] 'agreement among raft nodes before linearized reading' (duration: 163.040915ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:09.431251Z","caller":"traceutil/trace.go:171","msg":"trace[1763099608] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"415.936914ms","start":"2026-02-26T23:47:09.015294Z","end":"2026-02-26T23:47:09.431231Z","steps":["trace[1763099608] 'process raft request' (duration: 415.815531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:09.431378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:09.015278Z","time spent":"416.036036ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:47:09.431805Z","caller":"traceutil/trace.go:171","msg":"trace[394759247] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:936; }","duration":"394.090872ms","start":"2026-02-26T23:47:09.037703Z","end":"2026-02-26T23:47:09.431794Z","steps":["trace[394759247] 'read index received' (duration: 394.086932ms)","trace[394759247] 'applied index is now lower than readState.Index' (duration: 3.15µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:09.431939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.715768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T23:47:09.431975Z","caller":"traceutil/trace.go:171","msg":"trace[1419591234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"390.772859ms","start":"2026-02-26T23:47:09.041193Z","end":"2026-02-26T23:47:09.431966Z","steps":["trace[1419591234] 'agreement among raft nodes before linearized reading' (duration: 390.668147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:09.432004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:09.041185Z","time spent":"390.81273ms","remote":"127.0.0.1:50892","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-26T23:47:09.432197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.499731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T23:47:09.432232Z","caller":"traceutil/trace.go:171","msg":"trace[99336529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"394.533342ms","start":"2026-02-26T23:47:09.03769Z","end":"2026-02-26T23:47:09.432223Z","steps":["trace[99336529] 'agreement among raft nodes before linearized reading' (duration: 394.45235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:09.432257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:09.037629Z","time spent":"394.623003ms","remote":"127.0.0.1:50892","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-26T23:47:09.433356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.035493ms","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-26T23:47:09.433442Z","caller":"traceutil/trace.go:171","msg":"trace[1435291376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"391.159687ms","start":"2026-02-26T23:47:09.042261Z","end":"2026-02-26T23:47:09.43342Z","steps":["trace[1435291376] 'agreement among raft nodes before linearized reading' (duration: 390.885191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:09.433489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:09.042251Z","time spent":"391.229469ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:09.433523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.195248ms","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-26T23:47:09.433572Z","caller":"traceutil/trace.go:171","msg":"trace[1872903598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"391.259349ms","start":"2026-02-26T23:47:09.042298Z","end":"2026-02-26T23:47:09.433557Z","steps":["trace[1872903598] 'agreement among raft nodes before linearized reading' (duration: 390.85226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:09.433603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:09.042293Z","time spent":"391.30313ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T23:47:12.302964Z","caller":"traceutil/trace.go:171","msg":"trace[957267885] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"138.2781ms","start":"2026-02-26T23:47:12.16466Z","end":"2026-02-26T23:47:12.302939Z","steps":["trace[957267885] 'read index received' (duration: 138.078156ms)","trace[957267885] 'applied index is now lower than readState.Index' (duration: 199.054µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:12.303177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.502346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-jkwv6\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-02-26T23:47:12.303227Z","caller":"traceutil/trace.go:171","msg":"trace[829135942] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-jkwv6; range_end:; response_count:1; response_revision:923; }","duration":"138.571177ms","start":"2026-02-26T23:47:12.164645Z","end":"2026-02-26T23:47:12.303217Z","steps":["trace[829135942] 'agreement among raft nodes before linearized reading' (duration: 138.407134ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:12.303176Z","caller":"traceutil/trace.go:171","msg":"trace[1273991126] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"388.437398ms","start":"2026-02-26T23:47:11.914551Z","end":"2026-02-26T23:47:12.302988Z","steps":["trace[1273991126] 'process raft request' (duration: 388.224224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:12.303607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:11.91453Z","time spent":"388.971649ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:47:13.563953Z","caller":"traceutil/trace.go:171","msg":"trace[410469220] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"114.196078ms","start":"2026-02-26T23:47:13.449734Z","end":"2026-02-26T23:47:13.56393Z","steps":["trace[410469220] 'process raft request' (duration: 113.947193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.840324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192703286s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:14.84056Z","caller":"traceutil/trace.go:171","msg":"trace[2023245284] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.272280682s","start":"2026-02-26T23:47:13.568241Z","end":"2026-02-26T23:47:14.840522Z","steps":["trace[2023245284] 'process raft request' (duration: 79.30508ms)","trace[2023245284] 'compare' (duration: 1.192560212s)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:14.840612Z","caller":"traceutil/trace.go:171","msg":"trace[1421669364] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:968; }","duration":"1.266900593s","start":"2026-02-26T23:47:13.573694Z","end":"2026-02-26T23:47:14.840594Z","steps":["trace[1421669364] 'read index received' (duration: 73.86807ms)","trace[1421669364] 'applied index is now lower than readState.Index' (duration: 1.193031513s)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:14.840738Z","caller":"traceutil/trace.go:171","msg":"trace[231586341] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.271864782s","start":"2026-02-26T23:47:13.568863Z","end":"2026-02-26T23:47:14.840728Z","steps":["trace[231586341] 'process raft request' (duration: 1.271696949s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.840765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.568206Z","time spent":"1.272427115s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:14.840806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.568858Z","time spent":"1.271912183s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:47:14.840814Z","caller":"traceutil/trace.go:171","msg":"trace[544225970] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.272145848s","start":"2026-02-26T23:47:13.568631Z","end":"2026-02-26T23:47:14.840777Z","steps":["trace[544225970] 'process raft request' (duration: 1.271891353s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.840864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.633762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:47:14.840918Z","caller":"traceutil/trace.go:171","msg":"trace[118880026] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:933; }","duration":"933.755475ms","start":"2026-02-26T23:47:13.90715Z","end":"2026-02-26T23:47:14.840905Z","steps":["trace[118880026] 'agreement among raft nodes before linearized reading' (duration: 933.642413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.840959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267259461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:47:14.840969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.838594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:47:14.840988Z","caller":"traceutil/trace.go:171","msg":"trace[656168418] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:933; }","duration":"1.267296242s","start":"2026-02-26T23:47:13.573679Z","end":"2026-02-26T23:47:14.840976Z","steps":["trace[656168418] 'agreement among raft nodes before linearized reading' (duration: 1.267260301s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:14.840995Z","caller":"traceutil/trace.go:171","msg":"trace[2005066981] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.272467745s","start":"2026-02-26T23:47:13.568516Z","end":"2026-02-26T23:47:14.840984Z","steps":["trace[2005066981] 'process raft request' (duration: 1.271931903s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:14.841007Z","caller":"traceutil/trace.go:171","msg":"trace[2129493132] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:933; }","duration":"674.908856ms","start":"2026-02-26T23:47:14.166087Z","end":"2026-02-26T23:47:14.840995Z","steps":["trace[2129493132] 'agreement among raft nodes before linearized reading' (duration: 674.839734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.840994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.568617Z","time spent":"1.272271941s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:14.841044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:14.166067Z","time spent":"674.969407ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:14.841061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.568498Z","time spent":"1.272526706s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:14.840955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.907124Z","time spent":"933.821697ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:14.841015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:13.573674Z","time spent":"1.267335582s","remote":"127.0.0.1:56094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:14.840905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.517528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:14.841164Z","caller":"traceutil/trace.go:171","msg":"trace[1335777053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"455.812473ms","start":"2026-02-26T23:47:14.385342Z","end":"2026-02-26T23:47:14.841154Z","steps":["trace[1335777053] 'agreement among raft nodes before linearized reading' (duration: 455.520987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:14.841193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:14.385325Z","time spent":"455.861715ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:47:15.414463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.241741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:15.414614Z","caller":"traceutil/trace.go:171","msg":"trace[1387201256] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"241.831084ms","start":"2026-02-26T23:47:15.172765Z","end":"2026-02-26T23:47:15.414596Z","steps":["trace[1387201256] 'process raft request' (duration: 102.353958ms)","trace[1387201256] 'compare' (duration: 139.094937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:15.644975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.624594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:15.645132Z","caller":"traceutil/trace.go:171","msg":"trace[83558700] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"206.206358ms","start":"2026-02-26T23:47:15.438873Z","end":"2026-02-26T23:47:15.645079Z","steps":["trace[83558700] 'process raft request' (duration: 93.408411ms)","trace[83558700] 'compare' (duration: 112.47518ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:16.61689Z","caller":"traceutil/trace.go:171","msg":"trace[1835117212] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"241.254801ms","start":"2026-02-26T23:47:16.375605Z","end":"2026-02-26T23:47:16.61686Z","steps":["trace[1835117212] 'read index received' (duration: 241.107128ms)","trace[1835117212] 'applied index is now lower than readState.Index' (duration: 146.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:16.616938Z","caller":"traceutil/trace.go:171","msg":"trace[1715453422] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"323.66487ms","start":"2026-02-26T23:47:16.293232Z","end":"2026-02-26T23:47:16.616897Z","steps":["trace[1715453422] 'process raft request' (duration: 323.436155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:16.617061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.436815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:16.617138Z","caller":"traceutil/trace.go:171","msg":"trace[416121789] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:937; }","duration":"241.568158ms","start":"2026-02-26T23:47:16.37555Z","end":"2026-02-26T23:47:16.617118Z","steps":["trace[416121789] 'agreement among raft nodes before linearized reading' (duration: 241.409455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:16.617123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.690977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:47:16.617174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.293207Z","time spent":"323.841123ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T23:47:16.617207Z","caller":"traceutil/trace.go:171","msg":"trace[807013703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"234.82123ms","start":"2026-02-26T23:47:16.382364Z","end":"2026-02-26T23:47:16.617185Z","steps":["trace[807013703] 'agreement among raft nodes before linearized reading' (duration: 234.669446ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:17.233098Z","caller":"traceutil/trace.go:171","msg":"trace[441716963] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"612.530499ms","start":"2026-02-26T23:47:16.620541Z","end":"2026-02-26T23:47:17.233071Z","steps":["trace[441716963] 'process raft request' (duration: 612.405606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.233245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.620525Z","time spent":"612.657643ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T23:47:17.304035Z","caller":"traceutil/trace.go:171","msg":"trace[1426502971] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"458.184296ms","start":"2026-02-26T23:47:16.845827Z","end":"2026-02-26T23:47:17.304012Z","steps":["trace[1426502971] 'read index received' (duration: 387.270312ms)","trace[1426502971] 'applied index is now lower than readState.Index' (duration: 70.912794ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:17.304104Z","caller":"traceutil/trace.go:171","msg":"trace[179584411] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"523.426904ms","start":"2026-02-26T23:47:16.780619Z","end":"2026-02-26T23:47:17.304046Z","steps":["trace[179584411] 'process raft request' (duration: 523.23093ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:17.304134Z","caller":"traceutil/trace.go:171","msg":"trace[2030142709] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"278.216446ms","start":"2026-02-26T23:47:17.025873Z","end":"2026-02-26T23:47:17.304089Z","steps":["trace[2030142709] 'process raft request' (duration: 278.096324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.304229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.38038ms","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-26T23:47:17.304258Z","caller":"traceutil/trace.go:171","msg":"trace[539643317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"458.468672ms","start":"2026-02-26T23:47:16.845782Z","end":"2026-02-26T23:47:17.304251Z","steps":["trace[539643317] 'agreement among raft nodes before linearized reading' (duration: 458.314589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.304283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.84576Z","time spent":"458.518484ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:17.304273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.780591Z","time spent":"523.584938ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:17.972416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.503712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:17.972662Z","caller":"traceutil/trace.go:171","msg":"trace[324261840] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"663.543545ms","start":"2026-02-26T23:47:17.309096Z","end":"2026-02-26T23:47:17.972639Z","steps":["trace[324261840] 'process raft request' (duration: 663.488214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.972793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.309073Z","time spent":"663.655307ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:47:17.973027Z","caller":"traceutil/trace.go:171","msg":"trace[35898794] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"664.007825ms","start":"2026-02-26T23:47:17.30899Z","end":"2026-02-26T23:47:17.972998Z","steps":["trace[35898794] 'process raft request' (duration: 663.536085ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:17.973094Z","caller":"traceutil/trace.go:171","msg":"trace[1201111332] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"734.356997ms","start":"2026-02-26T23:47:17.238703Z","end":"2026-02-26T23:47:17.97306Z","steps":["trace[1201111332] 'process raft request' (duration: 110.122479ms)","trace[1201111332] 'compare' (duration: 623.373499ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:17.973152Z","caller":"traceutil/trace.go:171","msg":"trace[841813715] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"669.030476ms","start":"2026-02-26T23:47:17.304106Z","end":"2026-02-26T23:47:17.973136Z","steps":["trace[841813715] 'read index received' (duration: 44.731396ms)","trace[841813715] 'applied index is now lower than readState.Index' (duration: 624.29752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:17.973183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.308971Z","time spent":"664.129908ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:47:17.973219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.238688Z","time spent":"734.472029ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:47:17.973265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127242741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:47:17.973299Z","caller":"traceutil/trace.go:171","msg":"trace[1008234447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"1.127313053s","start":"2026-02-26T23:47:16.845975Z","end":"2026-02-26T23:47:17.973288Z","steps":["trace[1008234447] 'agreement among raft nodes before linearized reading' (duration: 1.12719343s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.97332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.845959Z","time spent":"1.127356294s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:17.973349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126777732s","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-26T23:47:17.973386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127460016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T23:47:17.973399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.420841ms","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-26T23:47:17.973424Z","caller":"traceutil/trace.go:171","msg":"trace[1489083232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"1.126908095s","start":"2026-02-26T23:47:16.846494Z","end":"2026-02-26T23:47:17.973402Z","steps":["trace[1489083232] 'agreement among raft nodes before linearized reading' (duration: 1.126651199s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:17.973433Z","caller":"traceutil/trace.go:171","msg":"trace[239726599] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:943; }","duration":"735.477932ms","start":"2026-02-26T23:47:17.237945Z","end":"2026-02-26T23:47:17.973423Z","steps":["trace[239726599] 'agreement among raft nodes before linearized reading' (duration: 735.38091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.973467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.237931Z","time spent":"735.530233ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T23:47:17.973474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.846463Z","time spent":"1.127002876s","remote":"127.0.0.1:50892","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-26T23:47:17.973491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.653315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:47:17.973521Z","caller":"traceutil/trace.go:171","msg":"trace[2041518913] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:943; }","duration":"667.725157ms","start":"2026-02-26T23:47:17.305787Z","end":"2026-02-26T23:47:17.973513Z","steps":["trace[2041518913] 'agreement among raft nodes before linearized reading' (duration: 667.673226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.97352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.610446ms","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-26T23:47:17.973559Z","caller":"traceutil/trace.go:171","msg":"trace[1353940252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"293.675628ms","start":"2026-02-26T23:47:17.679869Z","end":"2026-02-26T23:47:17.973545Z","steps":["trace[1353940252] 'agreement among raft nodes before linearized reading' (duration: 293.610876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.973558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.305766Z","time spent":"667.781458ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-26T23:47:17.97343Z","caller":"traceutil/trace.go:171","msg":"trace[1524546478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"1.127520058s","start":"2026-02-26T23:47:16.845899Z","end":"2026-02-26T23:47:17.973419Z","steps":["trace[1524546478] 'agreement among raft nodes before linearized reading' (duration: 1.127331164s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.97364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.706596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:47:17.973641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:16.845893Z","time spent":"1.127730712s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T23:47:17.973659Z","caller":"traceutil/trace.go:171","msg":"trace[1842612856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"589.748897ms","start":"2026-02-26T23:47:17.383904Z","end":"2026-02-26T23:47:17.973653Z","steps":["trace[1842612856] 'agreement among raft nodes before linearized reading' (duration: 589.720986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:17.973678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:17.383889Z","time spent":"589.784507ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:47:18.23048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.072917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:18.230583Z","caller":"traceutil/trace.go:171","msg":"trace[79299526] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"250.640328ms","start":"2026-02-26T23:47:17.979931Z","end":"2026-02-26T23:47:18.230571Z","steps":["trace[79299526] 'process raft request' (duration: 126.415078ms)","trace[79299526] 'compare' (duration: 123.988165ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:18.23071Z","caller":"traceutil/trace.go:171","msg":"trace[930487072] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"250.662288ms","start":"2026-02-26T23:47:17.980044Z","end":"2026-02-26T23:47:18.230706Z","steps":["trace[930487072] 'process raft request' (duration: 250.501115ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:18.231084Z","caller":"traceutil/trace.go:171","msg":"trace[757005212] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"249.967573ms","start":"2026-02-26T23:47:17.98109Z","end":"2026-02-26T23:47:18.231058Z","steps":["trace[757005212] 'process raft request' (duration: 249.870991ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:18.231162Z","caller":"traceutil/trace.go:171","msg":"trace[972701350] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"250.558915ms","start":"2026-02-26T23:47:17.980594Z","end":"2026-02-26T23:47:18.231153Z","steps":["trace[972701350] 'process raft request' (duration: 250.094375ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:20.420619Z","caller":"traceutil/trace.go:171","msg":"trace[358390683] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"435.412234ms","start":"2026-02-26T23:47:19.985182Z","end":"2026-02-26T23:47:20.420594Z","steps":["trace[358390683] 'process raft request' (duration: 435.278711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:20.420906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:19.985166Z","time spent":"435.594777ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:21.274246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.919939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T23:47:21.274548Z","caller":"traceutil/trace.go:171","msg":"trace[1931982922] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"445.804872ms","start":"2026-02-26T23:47:20.828726Z","end":"2026-02-26T23:47:21.274531Z","steps":["trace[1931982922] 'process raft request' (duration: 445.684369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:21.274683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.8287Z","time spent":"445.926425ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:21.275109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202480952s","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-26T23:47:21.275189Z","caller":"traceutil/trace.go:171","msg":"trace[144833212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:950; }","duration":"1.202612405s","start":"2026-02-26T23:47:20.072561Z","end":"2026-02-26T23:47:21.275173Z","steps":["trace[144833212] 'agreement among raft nodes before linearized reading' (duration: 1.20236979s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:21.275229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.072542Z","time spent":"1.202678387s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T23:47:21.274874Z","caller":"traceutil/trace.go:171","msg":"trace[1324314774] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"1.2019691s","start":"2026-02-26T23:47:20.072601Z","end":"2026-02-26T23:47:21.274571Z","steps":["trace[1324314774] 'read index received' (duration: 347.898554ms)","trace[1324314774] 'applied index is now lower than readState.Index' (duration: 854.066656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:21.275896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.91532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T23:47:21.275926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02556662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:21.275947Z","caller":"traceutil/trace.go:171","msg":"trace[484820007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"891.000072ms","start":"2026-02-26T23:47:20.384933Z","end":"2026-02-26T23:47:21.275933Z","steps":["trace[484820007] 'agreement among raft nodes before linearized reading' (duration: 890.903649ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:21.275972Z","caller":"traceutil/trace.go:171","msg":"trace[265959891] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:950; }","duration":"1.025651931s","start":"2026-02-26T23:47:20.250304Z","end":"2026-02-26T23:47:21.275956Z","steps":["trace[265959891] 'agreement among raft nodes before linearized reading' (duration: 1.025579999s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:21.276063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.250287Z","time spent":"1.025759254s","remote":"127.0.0.1:51072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:21.276177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03964125s","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-26T23:47:21.276526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039900106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T23:47:21.276603Z","caller":"traceutil/trace.go:171","msg":"trace[1929539254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"1.040118941s","start":"2026-02-26T23:47:20.236417Z","end":"2026-02-26T23:47:21.276536Z","steps":["trace[1929539254] 'agreement among raft nodes before linearized reading' (duration: 1.039439416s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:21.276674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.236397Z","time spent":"1.040262633s","remote":"127.0.0.1:50892","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-26T23:47:21.276338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.384921Z","time spent":"891.052342ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:47:21.276452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040139871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:47:21.276803Z","caller":"traceutil/trace.go:171","msg":"trace[256503083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:950; }","duration":"1.040533799s","start":"2026-02-26T23:47:20.236023Z","end":"2026-02-26T23:47:21.276557Z","steps":["trace[256503083] 'agreement among raft nodes before linearized reading' (duration: 1.039774953s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:21.276938Z","caller":"traceutil/trace.go:171","msg":"trace[465071029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"1.040642302s","start":"2026-02-26T23:47:20.236276Z","end":"2026-02-26T23:47:21.276918Z","steps":["trace[465071029] 'agreement among raft nodes before linearized reading' (duration: 1.04008196s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:21.277042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.236008Z","time spent":"1.040961549s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:21.277061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:20.23627Z","time spent":"1.040774575s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:21.47287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.719098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:21.473191Z","caller":"traceutil/trace.go:171","msg":"trace[549763171] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"191.773189ms","start":"2026-02-26T23:47:21.281395Z","end":"2026-02-26T23:47:21.473169Z","steps":["trace[549763171] 'process raft request' (duration: 191.683667ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:21.473142Z","caller":"traceutil/trace.go:171","msg":"trace[1761926569] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"193.313424ms","start":"2026-02-26T23:47:21.279798Z","end":"2026-02-26T23:47:21.473112Z","steps":["trace[1761926569] 'process raft request' (duration: 84.247418ms)","trace[1761926569] 'compare' (duration: 108.431362ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:21.473377Z","caller":"traceutil/trace.go:171","msg":"trace[1839786737] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"190.02205ms","start":"2026-02-26T23:47:21.283344Z","end":"2026-02-26T23:47:21.473366Z","steps":["trace[1839786737] 'process raft request' (duration: 189.777475ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:21.473654Z","caller":"traceutil/trace.go:171","msg":"trace[583281035] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"192.445513ms","start":"2026-02-26T23:47:21.280873Z","end":"2026-02-26T23:47:21.473318Z","steps":["trace[583281035] 'process raft request' (duration: 192.131057ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:22.612264Z","caller":"traceutil/trace.go:171","msg":"trace[1306963277] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"183.895666ms","start":"2026-02-26T23:47:22.428342Z","end":"2026-02-26T23:47:22.612237Z","steps":["trace[1306963277] 'process raft request' (duration: 183.62375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:22.894281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.602781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:22.894361Z","caller":"traceutil/trace.go:171","msg":"trace[172695986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"184.736504ms","start":"2026-02-26T23:47:22.709605Z","end":"2026-02-26T23:47:22.894342Z","steps":["trace[172695986] 'range keys from in-memory index tree' (duration: 184.52131ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:25.821351Z","caller":"traceutil/trace.go:171","msg":"trace[963432763] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"320.369606ms","start":"2026-02-26T23:47:25.500961Z","end":"2026-02-26T23:47:25.821331Z","steps":["trace[963432763] 'process raft request' (duration: 320.258833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:25.821481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:25.500941Z","time spent":"320.476388ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:47:25.856427Z","caller":"traceutil/trace.go:171","msg":"trace[2067953695] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"223.10053ms","start":"2026-02-26T23:47:25.633293Z","end":"2026-02-26T23:47:25.856394Z","steps":["trace[2067953695] 'read index received' (duration: 188.076368ms)","trace[2067953695] 'applied index is now lower than readState.Index' (duration: 35.023182ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:25.856601Z","caller":"traceutil/trace.go:171","msg":"trace[753239206] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"339.704022ms","start":"2026-02-26T23:47:25.516881Z","end":"2026-02-26T23:47:25.856585Z","steps":["trace[753239206] 'process raft request' (duration: 339.396255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:25.856816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:25.516866Z","time spent":"339.797304ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:25.856913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.606052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:25.857023Z","caller":"traceutil/trace.go:171","msg":"trace[2033873124] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:967; }","duration":"223.688224ms","start":"2026-02-26T23:47:25.633276Z","end":"2026-02-26T23:47:25.856964Z","steps":["trace[2033873124] 'agreement among raft nodes before linearized reading' (duration: 223.587102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:26.942792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.146441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:26.942912Z","caller":"traceutil/trace.go:171","msg":"trace[1674272794] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"617.831267ms","start":"2026-02-26T23:47:26.32506Z","end":"2026-02-26T23:47:26.942891Z","steps":["trace[1674272794] 'process raft request' (duration: 234.501852ms)","trace[1674272794] 'compare' (duration: 383.031368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:26.942966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:26.325028Z","time spent":"617.916659ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:47:27.690462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.517108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:27.690698Z","caller":"traceutil/trace.go:171","msg":"trace[1804036852] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1012; }","duration":"1.292204801s","start":"2026-02-26T23:47:26.398471Z","end":"2026-02-26T23:47:27.690676Z","steps":["trace[1804036852] 'read index received' (duration: 161.056052ms)","trace[1804036852] 'applied index is now lower than readState.Index' (duration: 1.131147368s)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:27.690734Z","caller":"traceutil/trace.go:171","msg":"trace[1631602677] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"1.319139584s","start":"2026-02-26T23:47:26.37158Z","end":"2026-02-26T23:47:27.69072Z","steps":["trace[1631602677] 'process raft request' (duration: 1.319025552s)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:27.690729Z","caller":"traceutil/trace.go:171","msg":"trace[1724908956] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.362087241s","start":"2026-02-26T23:47:26.32861Z","end":"2026-02-26T23:47:27.690697Z","steps":["trace[1724908956] 'process raft request' (duration: 793.228055ms)","trace[1724908956] 'compare' (duration: 568.352225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:27.690843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292374965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:27.690883Z","caller":"traceutil/trace.go:171","msg":"trace[1712221685] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:972; }","duration":"1.292417016s","start":"2026-02-26T23:47:26.398456Z","end":"2026-02-26T23:47:27.690873Z","steps":["trace[1712221685] 'agreement among raft nodes before linearized reading' (duration: 1.292311883s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:27.690925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:26.398409Z","time spent":"1.292507168s","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:27.690993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:26.371538Z","time spent":"1.319249167s","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:47:27.691544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:26.328581Z","time spent":"1.362208014s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:28.061854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.000046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:47:28.062111Z","caller":"traceutil/trace.go:171","msg":"trace[6213540] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"367.763181ms","start":"2026-02-26T23:47:27.694329Z","end":"2026-02-26T23:47:28.062092Z","steps":["trace[6213540] 'process raft request' (duration: 367.680999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.062189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.694301Z","time spent":"367.852303ms","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T23:47:28.062286Z","caller":"traceutil/trace.go:171","msg":"trace[1203283894] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"373.048057ms","start":"2026-02-26T23:47:27.689216Z","end":"2026-02-26T23:47:28.062264Z","steps":["trace[1203283894] 'process raft request' (duration: 169.501239ms)","trace[1203283894] 'compare' (duration: 202.748001ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:28.062357Z","caller":"traceutil/trace.go:171","msg":"trace[496799596] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"371.595265ms","start":"2026-02-26T23:47:27.690729Z","end":"2026-02-26T23:47:28.062324Z","steps":["trace[496799596] 'read index received' (duration: 168.000485ms)","trace[496799596] 'applied index is now lower than readState.Index' (duration: 203.589669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:28.062406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.689175Z","time spent":"373.16188ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:47:28.062491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.40603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T23:47:28.062526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.947299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T23:47:28.062543Z","caller":"traceutil/trace.go:171","msg":"trace[1220439867] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:974; }","duration":"487.481692ms","start":"2026-02-26T23:47:27.575046Z","end":"2026-02-26T23:47:28.062528Z","steps":["trace[1220439867] 'agreement among raft nodes before linearized reading' (duration: 487.37974ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:28.062566Z","caller":"traceutil/trace.go:171","msg":"trace[1052456706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"545.035191ms","start":"2026-02-26T23:47:27.517519Z","end":"2026-02-26T23:47:28.062554Z","steps":["trace[1052456706] 'agreement among raft nodes before linearized reading' (duration: 544.873827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.062582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.575034Z","time spent":"487.538373ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T23:47:28.062623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422663647s","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-26T23:47:28.062673Z","caller":"traceutil/trace.go:171","msg":"trace[1992146962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"1.42278777s","start":"2026-02-26T23:47:26.639874Z","end":"2026-02-26T23:47:28.062661Z","steps":["trace[1992146962] 'agreement among raft nodes before linearized reading' (duration: 1.422591275s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.062712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.210192ms","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-26T23:47:28.06271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.716808ms","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-26T23:47:28.06274Z","caller":"traceutil/trace.go:171","msg":"trace[699105220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"237.298133ms","start":"2026-02-26T23:47:27.825434Z","end":"2026-02-26T23:47:28.062732Z","steps":["trace[699105220] 'agreement among raft nodes before linearized reading' (duration: 237.192531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.062711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:26.639845Z","time spent":"1.422857052s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:28.062817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.709589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T23:47:28.062767Z","caller":"traceutil/trace.go:171","msg":"trace[794442272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"560.8056ms","start":"2026-02-26T23:47:27.501942Z","end":"2026-02-26T23:47:28.062747Z","steps":["trace[794442272] 'agreement among raft nodes before linearized reading' (duration: 560.645036ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:28.062866Z","caller":"traceutil/trace.go:171","msg":"trace[839723365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"201.812871ms","start":"2026-02-26T23:47:27.861044Z","end":"2026-02-26T23:47:28.062857Z","steps":["trace[839723365] 'agreement among raft nodes before linearized reading' (duration: 201.701869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.0629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.501905Z","time spent":"560.980993ms","remote":"127.0.0.1:50892","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-26T23:47:28.062949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.517484Z","time spent":"545.108892ms","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T23:47:28.062537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.354698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:28.063161Z","caller":"traceutil/trace.go:171","msg":"trace[2050096656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"678.040344ms","start":"2026-02-26T23:47:27.38511Z","end":"2026-02-26T23:47:28.063151Z","steps":["trace[2050096656] 'agreement among raft nodes before linearized reading' (duration: 677.337108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:28.063188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:47:27.385085Z","time spent":"678.094845ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T23:47:30.195249Z","caller":"traceutil/trace.go:171","msg":"trace[1242533546] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"110.796374ms","start":"2026-02-26T23:47:30.084423Z","end":"2026-02-26T23:47:30.195219Z","steps":["trace[1242533546] 'process raft request' (duration: 70.459284ms)","trace[1242533546] 'compare' (duration: 40.104565ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:30.195277Z","caller":"traceutil/trace.go:171","msg":"trace[1462187140] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"110.554058ms","start":"2026-02-26T23:47:30.084706Z","end":"2026-02-26T23:47:30.19526Z","steps":["trace[1462187140] 'process raft request' (duration: 110.507847ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:30.195325Z","caller":"traceutil/trace.go:171","msg":"trace[1115846548] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"110.732493ms","start":"2026-02-26T23:47:30.084553Z","end":"2026-02-26T23:47:30.195286Z","steps":["trace[1115846548] 'process raft request' (duration: 110.570049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:33.488979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.7782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:33.48908Z","caller":"traceutil/trace.go:171","msg":"trace[397390920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"103.944294ms","start":"2026-02-26T23:47:33.385114Z","end":"2026-02-26T23:47:33.489058Z","steps":["trace[397390920] 'range keys from in-memory index tree' (duration: 103.688948ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:34.328311Z","caller":"traceutil/trace.go:171","msg":"trace[1039523393] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"105.995668ms","start":"2026-02-26T23:47:34.222279Z","end":"2026-02-26T23:47:34.328274Z","steps":["trace[1039523393] 'read index received' (duration: 18.880417ms)","trace[1039523393] 'applied index is now lower than readState.Index' (duration: 87.113911ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:34.328442Z","caller":"traceutil/trace.go:171","msg":"trace[1759932319] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"113.041763ms","start":"2026-02-26T23:47:34.215342Z","end":"2026-02-26T23:47:34.328384Z","steps":["trace[1759932319] 'process raft request' (duration: 25.882401ms)","trace[1759932319] 'compare' (duration: 86.877356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:34.328653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.342826ms","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-26T23:47:34.328706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.306705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T23:47:34.328773Z","caller":"traceutil/trace.go:171","msg":"trace[283725152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"106.515ms","start":"2026-02-26T23:47:34.222241Z","end":"2026-02-26T23:47:34.328756Z","steps":["trace[283725152] 'agreement among raft nodes before linearized reading' (duration: 106.252854ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:34.328801Z","caller":"traceutil/trace.go:171","msg":"trace[570793637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"106.437557ms","start":"2026-02-26T23:47:34.22234Z","end":"2026-02-26T23:47:34.328777Z","steps":["trace[570793637] 'agreement among raft nodes before linearized reading' (duration: 106.177092ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:34.449666Z","caller":"traceutil/trace.go:171","msg":"trace[1029133935] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"115.874515ms","start":"2026-02-26T23:47:34.333745Z","end":"2026-02-26T23:47:34.44962Z","steps":["trace[1029133935] 'process raft request' (duration: 104.977516ms)","trace[1029133935] 'compare' (duration: 10.721736ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:34.482666Z","caller":"traceutil/trace.go:171","msg":"trace[905563497] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"100.903576ms","start":"2026-02-26T23:47:34.381727Z","end":"2026-02-26T23:47:34.48263Z","steps":["trace[905563497] 'read index received' (duration: 57.005378ms)","trace[905563497] 'applied index is now lower than readState.Index' (duration: 43.897128ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:34.482728Z","caller":"traceutil/trace.go:171","msg":"trace[396369109] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"145.834616ms","start":"2026-02-26T23:47:34.336866Z","end":"2026-02-26T23:47:34.4827Z","steps":["trace[396369109] 'process raft request' (duration: 145.603031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:34.48282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.075569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:47:34.482879Z","caller":"traceutil/trace.go:171","msg":"trace[87271241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"101.199462ms","start":"2026-02-26T23:47:34.381667Z","end":"2026-02-26T23:47:34.482866Z","steps":["trace[87271241] 'agreement among raft nodes before linearized reading' (duration: 101.0984ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:36.445453Z","caller":"traceutil/trace.go:171","msg":"trace[1629342967] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"112.789768ms","start":"2026-02-26T23:47:36.33264Z","end":"2026-02-26T23:47:36.44543Z","steps":["trace[1629342967] 'read index received' (duration: 14.409538ms)","trace[1629342967] 'applied index is now lower than readState.Index' (duration: 98.37905ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:36.44558Z","caller":"traceutil/trace.go:171","msg":"trace[1238181435] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"231.941547ms","start":"2026-02-26T23:47:36.213625Z","end":"2026-02-26T23:47:36.445566Z","steps":["trace[1238181435] 'process raft request' (duration: 133.426724ms)","trace[1238181435] 'compare' (duration: 98.274577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:36.44573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.073904ms","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-26T23:47:36.445805Z","caller":"traceutil/trace.go:171","msg":"trace[738758644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"113.188666ms","start":"2026-02-26T23:47:36.332598Z","end":"2026-02-26T23:47:36.445787Z","steps":["trace[738758644] 'agreement among raft nodes before linearized reading' (duration: 113.029453ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:36.66516Z","caller":"traceutil/trace.go:171","msg":"trace[1369778250] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"211.238739ms","start":"2026-02-26T23:47:36.453894Z","end":"2026-02-26T23:47:36.665133Z","steps":["trace[1369778250] 'read index received' (duration: 83.833669ms)","trace[1369778250] 'applied index is now lower than readState.Index' (duration: 127.40403ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:36.66517Z","caller":"traceutil/trace.go:171","msg":"trace[2099158450] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"214.349317ms","start":"2026-02-26T23:47:36.450793Z","end":"2026-02-26T23:47:36.665142Z","steps":["trace[2099158450] 'process raft request' (duration: 135.285253ms)","trace[2099158450] 'compare' (duration: 78.953721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:47:36.66534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.438913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T23:47:36.665397Z","caller":"traceutil/trace.go:171","msg":"trace[1750197835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"211.532545ms","start":"2026-02-26T23:47:36.453855Z","end":"2026-02-26T23:47:36.665388Z","steps":["trace[1750197835] 'agreement among raft nodes before linearized reading' (duration: 211.377842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:36.665429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.152162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T23:47:36.665502Z","caller":"traceutil/trace.go:171","msg":"trace[639681844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"179.250683ms","start":"2026-02-26T23:47:36.486238Z","end":"2026-02-26T23:47:36.665488Z","steps":["trace[639681844] 'agreement among raft nodes before linearized reading' (duration: 179.08894ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:47:47.868505Z","caller":"traceutil/trace.go:171","msg":"trace[537958285] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"137.179307ms","start":"2026-02-26T23:47:47.731302Z","end":"2026-02-26T23:47:47.868482Z","steps":["trace[537958285] 'read index received' (duration: 136.150124ms)","trace[537958285] 'applied index is now lower than readState.Index' (duration: 1.028193ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:47:47.868777Z","caller":"traceutil/trace.go:171","msg":"trace[1120079684] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"153.024337ms","start":"2026-02-26T23:47:47.715727Z","end":"2026-02-26T23:47:47.868751Z","steps":["trace[1120079684] 'process raft request' (duration: 151.729798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:47:47.868792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.469073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-26T23:47:47.868842Z","caller":"traceutil/trace.go:171","msg":"trace[581975775] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1077; }","duration":"137.548995ms","start":"2026-02-26T23:47:47.731279Z","end":"2026-02-26T23:47:47.868828Z","steps":["trace[581975775] 'agreement among raft nodes before linearized reading' (duration: 137.33518ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:48:40.134369Z","caller":"traceutil/trace.go:171","msg":"trace[1787048488] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"155.201915ms","start":"2026-02-26T23:48:39.979112Z","end":"2026-02-26T23:48:40.134314Z","steps":["trace[1787048488] 'process raft request' (duration: 155.007761ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:48:56.932904Z","caller":"traceutil/trace.go:171","msg":"trace[1188172391] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"127.726128ms","start":"2026-02-26T23:48:56.805137Z","end":"2026-02-26T23:48:56.932863Z","steps":["trace[1188172391] 'process raft request' (duration: 70.68103ms)","trace[1188172391] 'compare' (duration: 56.799473ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:48:57.457461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.854477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:48:57.457674Z","caller":"traceutil/trace.go:171","msg":"trace[1372305691] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"214.199897ms","start":"2026-02-26T23:48:57.243415Z","end":"2026-02-26T23:48:57.457615Z","steps":["trace[1372305691] 'process raft request' (duration: 43.149423ms)","trace[1372305691] 'compare' (duration: 169.251824ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:49:13.335966Z","caller":"traceutil/trace.go:171","msg":"trace[1003709287] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"142.144167ms","start":"2026-02-26T23:49:13.193779Z","end":"2026-02-26T23:49:13.335923Z","steps":["trace[1003709287] 'process raft request' (duration: 113.943325ms)","trace[1003709287] 'compare' (duration: 27.946807ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:49:13.629438Z","caller":"traceutil/trace.go:171","msg":"trace[1297344490] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"191.102327ms","start":"2026-02-26T23:49:13.438302Z","end":"2026-02-26T23:49:13.629405Z","steps":["trace[1297344490] 'process raft request' (duration: 129.918277ms)","trace[1297344490] 'compare' (duration: 61.021986ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:49:13.76613Z","caller":"traceutil/trace.go:171","msg":"trace[1172894318] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"132.196147ms","start":"2026-02-26T23:49:13.633906Z","end":"2026-02-26T23:49:13.766102Z","steps":["trace[1172894318] 'process raft request' (duration: 120.137191ms)","trace[1172894318] 'compare' (duration: 11.957854ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:49:13.820966Z","caller":"traceutil/trace.go:171","msg":"trace[489486757] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"186.080596ms","start":"2026-02-26T23:49:13.634847Z","end":"2026-02-26T23:49:13.820927Z","steps":["trace[489486757] 'process raft request' (duration: 185.905002ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:49:34.046744Z","caller":"traceutil/trace.go:171","msg":"trace[2118544758] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"163.839096ms","start":"2026-02-26T23:49:33.882863Z","end":"2026-02-26T23:49:34.046702Z","steps":["trace[2118544758] 'process raft request' (duration: 72.069151ms)","trace[2118544758] 'compare' (duration: 91.617681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:50:44.89824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.627715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T23:50:44.898335Z","caller":"traceutil/trace.go:171","msg":"trace[1451938955] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1830; }","duration":"162.741967ms","start":"2026-02-26T23:50:44.735576Z","end":"2026-02-26T23:50:44.898317Z","steps":["trace[1451938955] 'count revisions from in-memory index tree' (duration: 162.529362ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:03.838018Z","caller":"traceutil/trace.go:171","msg":"trace[412123979] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"113.948843ms","start":"2026-02-26T23:51:03.724043Z","end":"2026-02-26T23:51:03.837992Z","steps":["trace[412123979] 'read index received' (duration: 113.79667ms)","trace[412123979] 'applied index is now lower than readState.Index' (duration: 151.133µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:51:03.838191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.446846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:51:03.83824Z","caller":"traceutil/trace.go:171","msg":"trace[1320771596] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1913; }","duration":"110.54414ms","start":"2026-02-26T23:51:03.727684Z","end":"2026-02-26T23:51:03.838228Z","steps":["trace[1320771596] 'agreement among raft nodes before linearized reading' (duration: 110.450816ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:03.838123Z","caller":"traceutil/trace.go:171","msg":"trace[198523012] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"145.217142ms","start":"2026-02-26T23:51:03.692868Z","end":"2026-02-26T23:51:03.838085Z","steps":["trace[198523012] 'process raft request' (duration: 144.986097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:03.838382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.314982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:90329"} {"level":"info","ts":"2026-02-26T23:51:03.838478Z","caller":"traceutil/trace.go:171","msg":"trace[1763306925] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:1913; }","duration":"114.443975ms","start":"2026-02-26T23:51:03.724008Z","end":"2026-02-26T23:51:03.838452Z","steps":["trace[1763306925] 'agreement among raft nodes before linearized reading' (duration: 114.109787ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:04.234332Z","caller":"traceutil/trace.go:171","msg":"trace[2090966446] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"215.826501ms","start":"2026-02-26T23:51:04.018481Z","end":"2026-02-26T23:51:04.234308Z","steps":["trace[2090966446] 'read index received' (duration: 208.497369ms)","trace[2090966446] 'applied index is now lower than readState.Index' (duration: 7.328292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:51:04.234354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.823797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-02-26T23:51:04.234422Z","caller":"traceutil/trace.go:171","msg":"trace[2145421954] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"217.55244ms","start":"2026-02-26T23:51:04.016861Z","end":"2026-02-26T23:51:04.234413Z","steps":["trace[2145421954] 'process raft request' (duration: 210.107805ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:04.234426Z","caller":"traceutil/trace.go:171","msg":"trace[1744261795] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1915; }","duration":"218.94814ms","start":"2026-02-26T23:51:04.015461Z","end":"2026-02-26T23:51:04.234409Z","steps":["trace[1744261795] 'range keys from in-memory index tree' (duration: 218.688894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.234503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.997334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:51:04.234579Z","caller":"traceutil/trace.go:171","msg":"trace[632594795] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1916; }","duration":"216.083086ms","start":"2026-02-26T23:51:04.018477Z","end":"2026-02-26T23:51:04.23456Z","steps":["trace[632594795] 'agreement among raft nodes before linearized reading' (duration: 215.970074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.316129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.105223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-26T23:51:04.316203Z","caller":"traceutil/trace.go:171","msg":"trace[753618483] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1917; }","duration":"238.234715ms","start":"2026-02-26T23:51:04.077952Z","end":"2026-02-26T23:51:04.316187Z","steps":["trace[753618483] 'agreement among raft nodes before linearized reading' (duration: 238.065602ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:04.316203Z","caller":"traceutil/trace.go:171","msg":"trace[654778064] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"182.751282ms","start":"2026-02-26T23:51:04.133439Z","end":"2026-02-26T23:51:04.31619Z","steps":["trace[654778064] 'process raft request' (duration: 182.402894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.316227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.970831ms","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-26T23:51:04.316286Z","caller":"traceutil/trace.go:171","msg":"trace[1372140173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1917; }","duration":"108.072003ms","start":"2026-02-26T23:51:04.208199Z","end":"2026-02-26T23:51:04.316271Z","steps":["trace[1372140173] 'agreement among raft nodes before linearized reading' (duration: 107.890849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.316281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.159326ms","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-26T23:51:04.316333Z","caller":"traceutil/trace.go:171","msg":"trace[866227741] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1917; }","duration":"210.261328ms","start":"2026-02-26T23:51:04.106059Z","end":"2026-02-26T23:51:04.31632Z","steps":["trace[866227741] 'agreement among raft nodes before linearized reading' (duration: 210.115775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.316151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.205829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/\" range_end:\"/registry/roles/kube-public0\" limit:500 ","response":"range_response_count:2 size:1073"} {"level":"info","ts":"2026-02-26T23:51:04.316384Z","caller":"traceutil/trace.go:171","msg":"trace[43604723] range","detail":"{range_begin:/registry/roles/kube-public/; range_end:/registry/roles/kube-public0; response_count:2; response_revision:1917; }","duration":"240.480915ms","start":"2026-02-26T23:51:04.075896Z","end":"2026-02-26T23:51:04.316376Z","steps":["trace[43604723] 'agreement among raft nodes before linearized reading' (duration: 240.186778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.495119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.15663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:51:04.495344Z","caller":"traceutil/trace.go:171","msg":"trace[1057778702] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"175.095422ms","start":"2026-02-26T23:51:04.320177Z","end":"2026-02-26T23:51:04.495273Z","steps":["trace[1057778702] 'process raft request' (duration: 74.408771ms)","trace[1057778702] 'compare' (duration: 99.972696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:51:04.49542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.046051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-02-26T23:51:04.49562Z","caller":"traceutil/trace.go:171","msg":"trace[1599575207] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"174.940119ms","start":"2026-02-26T23:51:04.320372Z","end":"2026-02-26T23:51:04.495312Z","steps":["trace[1599575207] 'process raft request' (duration: 174.855337ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:51:04.495709Z","caller":"traceutil/trace.go:171","msg":"trace[856447056] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"174.97262ms","start":"2026-02-26T23:51:04.320366Z","end":"2026-02-26T23:51:04.495339Z","steps":["trace[856447056] 'read index received' (duration: 74.231387ms)","trace[856447056] 'applied index is now lower than readState.Index' (duration: 100.740323ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:51:04.495864Z","caller":"traceutil/trace.go:171","msg":"trace[347839194] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1919; }","duration":"175.105302ms","start":"2026-02-26T23:51:04.320344Z","end":"2026-02-26T23:51:04.49545Z","steps":["trace[347839194] 'agreement among raft nodes before linearized reading' (duration: 174.99792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.496131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.123621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:51:04.496214Z","caller":"traceutil/trace.go:171","msg":"trace[1013516041] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1919; }","duration":"106.246164ms","start":"2026-02-26T23:51:04.389948Z","end":"2026-02-26T23:51:04.496194Z","steps":["trace[1013516041] 'agreement among raft nodes before linearized reading' (duration: 106.108591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.496225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.471082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:51:04.496275Z","caller":"traceutil/trace.go:171","msg":"trace[749830456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1919; }","duration":"113.565735ms","start":"2026-02-26T23:51:04.382696Z","end":"2026-02-26T23:51:04.496262Z","steps":["trace[749830456] 'agreement among raft nodes before linearized reading' (duration: 113.465863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:51:04.496494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.337005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:101685"} {"level":"info","ts":"2026-02-26T23:51:04.496539Z","caller":"traceutil/trace.go:171","msg":"trace[1533885199] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:1919; }","duration":"174.400448ms","start":"2026-02-26T23:51:04.322127Z","end":"2026-02-26T23:51:04.496527Z","steps":["trace[1533885199] 'agreement among raft nodes before linearized reading' (duration: 173.716223ms)"],"step_count":1}