{"level":"warn","ts":"2026-02-22T17:39:16.734656Z","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-22T17:39:16.73477Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-22T17:39:16.734854Z","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-22T17:39:16.734873Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T17:39:16.734901Z","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-22T17:39:16.735756Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-22T17:39:16.735847Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-22T17:39:16.741038Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.931276ms"} {"level":"info","ts":"2026-02-22T17:39:16.750614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-22T17:39:16.750724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T17:39:16.750763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-22T17:39:16.750788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T17:39:16.750806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-22T17:39:16.750855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-22T17:39:16.757824Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T17:39:16.76024Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T17:39:16.761685Z","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-22T17:39:16.763842Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T17:39:16.764001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T17:39:16.764318Z","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-22T17:39:16.764497Z","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-22T17:39:16.764628Z","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-22T17:39:16.766179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-22T17:39:16.769642Z","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-22T17:39:16.769982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T17:39:16.770044Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T17:39:16.770624Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T17:39:16.770717Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T17:39:16.77244Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T17:39:17.552009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T17:39:17.55208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T17:39:17.55214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-22T17:39:17.552162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-22T17:39:17.552173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T17:39:17.552189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-22T17:39:17.552203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T17:39:17.554685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T17:39:17.554712Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:39:17.554737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:39:17.554904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:39:17.55523Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T17:39:17.55526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T17:39:17.555488Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:39:17.555651Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:39:17.555742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:39:17.557525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-22T17:39:17.558154Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T17:39:30.809582Z","caller":"traceutil/trace.go:171","msg":"trace[397756700] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"170.595904ms","start":"2026-02-22T17:39:30.638965Z","end":"2026-02-22T17:39:30.809561Z","steps":["trace[397756700] 'read index received' (duration: 170.372853ms)","trace[397756700] 'applied index is now lower than readState.Index' (duration: 222.231µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:30.809753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.764195ms","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-22T17:39:30.809822Z","caller":"traceutil/trace.go:171","msg":"trace[1233597214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:359; }","duration":"170.871485ms","start":"2026-02-22T17:39:30.638939Z","end":"2026-02-22T17:39:30.809811Z","steps":["trace[1233597214] 'agreement among raft nodes before linearized reading' (duration: 170.727634ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:30.810115Z","caller":"traceutil/trace.go:171","msg":"trace[2040760184] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"204.677896ms","start":"2026-02-22T17:39:30.605426Z","end":"2026-02-22T17:39:30.810104Z","steps":["trace[2040760184] 'process raft request' (duration: 203.963721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:37.035867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.975662ms","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-22T17:39:37.035991Z","caller":"traceutil/trace.go:171","msg":"trace[1897348177] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:407; }","duration":"255.118173ms","start":"2026-02-22T17:39:36.780857Z","end":"2026-02-22T17:39:37.035975Z","steps":["trace[1897348177] 'range keys from in-memory index tree' (duration: 254.958832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:37.036125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217937448s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:39:37.036234Z","caller":"traceutil/trace.go:171","msg":"trace[680073733] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"657.213872ms","start":"2026-02-22T17:39:36.378996Z","end":"2026-02-22T17:39:37.03621Z","steps":["trace[680073733] 'read index received' (duration: 43.17µs)","trace[680073733] 'applied index is now lower than readState.Index' (duration: 657.168422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:37.036455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.937796ms","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-22T17:39:37.036463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.118939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:39:37.036485Z","caller":"traceutil/trace.go:171","msg":"trace[163799190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"142.991556ms","start":"2026-02-22T17:39:36.893484Z","end":"2026-02-22T17:39:37.036476Z","steps":["trace[163799190] 'agreement among raft nodes before linearized reading' (duration: 142.895706ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:37.036502Z","caller":"traceutil/trace.go:171","msg":"trace[1985289463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"207.206239ms","start":"2026-02-22T17:39:36.829285Z","end":"2026-02-22T17:39:37.036491Z","steps":["trace[1985289463] 'agreement among raft nodes before linearized reading' (duration: 207.063498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:37.036483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.458233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:37.036573Z","caller":"traceutil/trace.go:171","msg":"trace[294832047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"657.606894ms","start":"2026-02-22T17:39:36.378944Z","end":"2026-02-22T17:39:37.036551Z","steps":["trace[294832047] 'agreement among raft nodes before linearized reading' (duration: 657.408913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:37.03662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:36.378925Z","time spent":"657.685275ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:39:37.616336Z","caller":"traceutil/trace.go:171","msg":"trace[149057823] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"568.839651ms","start":"2026-02-22T17:39:37.047469Z","end":"2026-02-22T17:39:37.616309Z","steps":["trace[149057823] 'process raft request' (duration: 568.725581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:37.616846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:37.047455Z","time spent":"568.994842ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:39:38.889087Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.272830661s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:39:38.889506Z","caller":"traceutil/trace.go:171","msg":"trace[514177815] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.841866943s","start":"2026-02-22T17:39:37.047616Z","end":"2026-02-22T17:39:38.889483Z","steps":["trace[514177815] 'process raft request' (duration: 1.841669352s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:38.889658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:37.04761Z","time spent":"1.841980733s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:38.889694Z","caller":"traceutil/trace.go:171","msg":"trace[1657488891] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"1.4869702s","start":"2026-02-22T17:39:37.402699Z","end":"2026-02-22T17:39:38.889669Z","steps":["trace[1657488891] 'read index received' (duration: 213.624646ms)","trace[1657488891] 'applied index is now lower than readState.Index' (duration: 1.273343914s)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:39:38.889756Z","caller":"traceutil/trace.go:171","msg":"trace[679767228] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.781150757s","start":"2026-02-22T17:39:37.108557Z","end":"2026-02-22T17:39:38.889707Z","steps":["trace[679767228] 'process raft request' (duration: 1.780873856s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:38.8899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487177401s","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-22T17:39:38.889941Z","caller":"traceutil/trace.go:171","msg":"trace[1141889016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"1.487272481s","start":"2026-02-22T17:39:37.402658Z","end":"2026-02-22T17:39:38.889931Z","steps":["trace[1141889016] 'agreement among raft nodes before linearized reading' (duration: 1.48712913s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:38.889982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:37.402642Z","time spent":"1.487331491s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:39:38.89002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331972518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:39:38.890015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.684338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:38.890056Z","caller":"traceutil/trace.go:171","msg":"trace[1615042665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"1.332043769s","start":"2026-02-22T17:39:37.558002Z","end":"2026-02-22T17:39:38.890046Z","steps":["trace[1615042665] 'agreement among raft nodes before linearized reading' (duration: 1.331979188s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:38.890063Z","caller":"traceutil/trace.go:171","msg":"trace[339780610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"514.759489ms","start":"2026-02-22T17:39:38.375292Z","end":"2026-02-22T17:39:38.890051Z","steps":["trace[339780610] 'agreement among raft nodes before linearized reading' (duration: 514.687998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:38.890083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:37.557983Z","time spent":"1.332094219s","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:39:38.890101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:38.375274Z","time spent":"514.819959ms","remote":"127.0.0.1:34668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:39:38.889939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:37.108546Z","time spent":"1.781301008s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:39.468393Z","caller":"traceutil/trace.go:171","msg":"trace[967759968] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"192.047294ms","start":"2026-02-22T17:39:39.276316Z","end":"2026-02-22T17:39:39.468363Z","steps":["trace[967759968] 'process raft request' (duration: 191.783323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:39.767225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.23332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:39:39.767305Z","caller":"traceutil/trace.go:171","msg":"trace[1648626653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"147.36285ms","start":"2026-02-22T17:39:39.619923Z","end":"2026-02-22T17:39:39.767286Z","steps":["trace[1648626653] 'range keys from in-memory index tree' (duration: 147.1405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.613539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.210217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:39:45.613653Z","caller":"traceutil/trace.go:171","msg":"trace[1036572832] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"731.983895ms","start":"2026-02-22T17:39:44.88165Z","end":"2026-02-22T17:39:45.613634Z","steps":["trace[1036572832] 'process raft request' (duration: 113.601028ms)","trace[1036572832] 'compare' (duration: 617.794724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:45.613709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:44.881639Z","time spent":"732.044255ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4620,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:45.615851Z","caller":"traceutil/trace.go:171","msg":"trace[1637831849] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"722.334509ms","start":"2026-02-22T17:39:44.893472Z","end":"2026-02-22T17:39:45.615807Z","steps":["trace[1637831849] 'process raft request' (duration: 720.860052ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:45.615955Z","caller":"traceutil/trace.go:171","msg":"trace[1611628073] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:469; }","duration":"691.156752ms","start":"2026-02-22T17:39:44.924727Z","end":"2026-02-22T17:39:45.615884Z","steps":["trace[1611628073] 'read index received' (duration: 70.640414ms)","trace[1611628073] 'applied index is now lower than readState.Index' (duration: 620.254766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:45.616049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:44.893451Z","time spent":"722.50059ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317259,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:39:45.626874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.932315ms","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-22T17:39:45.62694Z","caller":"traceutil/trace.go:171","msg":"trace[420383003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"692.033986ms","start":"2026-02-22T17:39:44.934891Z","end":"2026-02-22T17:39:45.626925Z","steps":["trace[420383003] 'agreement among raft nodes before linearized reading' (duration: 691.894905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.626933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.998031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-02-22T17:39:45.626975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:44.934881Z","time spent":"692.086166ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:39:45.626998Z","caller":"traceutil/trace.go:171","msg":"trace[64807123] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:455; }","duration":"560.089682ms","start":"2026-02-22T17:39:45.066892Z","end":"2026-02-22T17:39:45.626982Z","steps":["trace[64807123] 'agreement among raft nodes before linearized reading' (duration: 559.939491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.627045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:45.066886Z","time spent":"560.152292ms","remote":"127.0.0.1:34856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":560,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-22T17:39:45.627143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.265352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-22T17:39:45.627172Z","caller":"traceutil/trace.go:171","msg":"trace[530647538] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:455; }","duration":"560.328933ms","start":"2026-02-22T17:39:45.066835Z","end":"2026-02-22T17:39:45.627164Z","steps":["trace[530647538] 'agreement among raft nodes before linearized reading' (duration: 560.280202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.627195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:45.066815Z","time spent":"560.374733ms","remote":"127.0.0.1:34856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":542,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-22T17:39:45.627263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.156806ms","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-22T17:39:45.627288Z","caller":"traceutil/trace.go:171","msg":"trace[2124102595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"692.198076ms","start":"2026-02-22T17:39:44.935084Z","end":"2026-02-22T17:39:45.627282Z","steps":["trace[2124102595] 'agreement among raft nodes before linearized reading' (duration: 692.157596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.627308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:44.935076Z","time spent":"692.226026ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:39:45.627454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.712566ms","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-22T17:39:45.627506Z","caller":"traceutil/trace.go:171","msg":"trace[1099081170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"702.797676ms","start":"2026-02-22T17:39:44.924696Z","end":"2026-02-22T17:39:45.627494Z","steps":["trace[1099081170] 'agreement among raft nodes before linearized reading' (duration: 691.386052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:45.627537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:44.924685Z","time spent":"702.846806ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:39:48.561038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.024426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:39:48.561127Z","caller":"traceutil/trace.go:171","msg":"trace[1313906149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"758.168837ms","start":"2026-02-22T17:39:47.802941Z","end":"2026-02-22T17:39:48.56111Z","steps":["trace[1313906149] 'range keys from in-memory index tree' (duration: 757.924145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:48.561253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:47.802919Z","time spent":"758.321027ms","remote":"127.0.0.1:34910","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-22T17:39:48.561343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.138196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:48.561418Z","caller":"traceutil/trace.go:171","msg":"trace[1765256672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"183.265286ms","start":"2026-02-22T17:39:48.378133Z","end":"2026-02-22T17:39:48.561399Z","steps":["trace[1765256672] 'range keys from in-memory index tree' (duration: 183.052186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:48.561617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004046589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:48.561662Z","caller":"traceutil/trace.go:171","msg":"trace[1063967030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"1.00412746s","start":"2026-02-22T17:39:47.557524Z","end":"2026-02-22T17:39:48.561652Z","steps":["trace[1063967030] 'range keys from in-memory index tree' (duration: 1.003953459s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:48.561691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:47.5575Z","time spent":"1.00418381s","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:39:48.561838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.858359ms","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-22T17:39:48.561861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.3361ms","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-22T17:39:48.561913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.870589ms","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-22T17:39:48.561914Z","caller":"traceutil/trace.go:171","msg":"trace[1229915402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"927.43518ms","start":"2026-02-22T17:39:47.634465Z","end":"2026-02-22T17:39:48.5619Z","steps":["trace[1229915402] 'range keys from in-memory index tree' (duration: 927.2229ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:48.56194Z","caller":"traceutil/trace.go:171","msg":"trace[1673597679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"922.91466ms","start":"2026-02-22T17:39:47.639019Z","end":"2026-02-22T17:39:48.561934Z","steps":["trace[1673597679] 'range keys from in-memory index tree' (duration: 922.830779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:48.56195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:47.63444Z","time spent":"927.501862ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:39:48.56197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:47.639013Z","time spent":"922.94977ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:39:48.561879Z","caller":"traceutil/trace.go:171","msg":"trace[1207072076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"922.920659ms","start":"2026-02-22T17:39:47.638948Z","end":"2026-02-22T17:39:48.561869Z","steps":["trace[1207072076] 'range keys from in-memory index tree' (duration: 922.645728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:48.562151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:47.638937Z","time spent":"923.183881ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:39:54.51275Z","caller":"traceutil/trace.go:171","msg":"trace[1716631817] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"134.691569ms","start":"2026-02-22T17:39:54.378036Z","end":"2026-02-22T17:39:54.512728Z","steps":["trace[1716631817] 'read index received' (duration: 134.485508ms)","trace[1716631817] 'applied index is now lower than readState.Index' (duration: 205.241µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:54.512856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.800919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:54.512838Z","caller":"traceutil/trace.go:171","msg":"trace[126705228] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"199.351596ms","start":"2026-02-22T17:39:54.313449Z","end":"2026-02-22T17:39:54.5128Z","steps":["trace[126705228] 'process raft request' (duration: 199.119825ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:54.512882Z","caller":"traceutil/trace.go:171","msg":"trace[1157531929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"134.874941ms","start":"2026-02-22T17:39:54.377999Z","end":"2026-02-22T17:39:54.512874Z","steps":["trace[1157531929] 'agreement among raft nodes before linearized reading' (duration: 134.81395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:54.833589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.524006ms","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-22T17:39:54.833671Z","caller":"traceutil/trace.go:171","msg":"trace[1151381581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"242.634446ms","start":"2026-02-22T17:39:54.591016Z","end":"2026-02-22T17:39:54.833651Z","steps":["trace[1151381581] 'range keys from in-memory index tree' (duration: 242.374354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:54.833671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.572581ms","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-22T17:39:54.833614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.146088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:39:54.833763Z","caller":"traceutil/trace.go:171","msg":"trace[426615167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"243.320628ms","start":"2026-02-22T17:39:54.590422Z","end":"2026-02-22T17:39:54.833742Z","steps":["trace[426615167] 'range keys from in-memory index tree' (duration: 243.068418ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:54.833797Z","caller":"traceutil/trace.go:171","msg":"trace[1010797211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:503; }","duration":"243.713011ms","start":"2026-02-22T17:39:54.590028Z","end":"2026-02-22T17:39:54.833741Z","steps":["trace[1010797211] 'range keys from in-memory index tree' (duration: 243.373509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:54.833761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.911038ms","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-22T17:39:54.833877Z","caller":"traceutil/trace.go:171","msg":"trace[111337730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"226.078569ms","start":"2026-02-22T17:39:54.607772Z","end":"2026-02-22T17:39:54.833851Z","steps":["trace[111337730] 'range keys from in-memory index tree' (duration: 225.716048ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:55.109286Z","caller":"traceutil/trace.go:171","msg":"trace[1416296384] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"271.99926ms","start":"2026-02-22T17:39:54.837256Z","end":"2026-02-22T17:39:55.109255Z","steps":["trace[1416296384] 'process raft request' (duration: 271.811039ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:55.372045Z","caller":"traceutil/trace.go:171","msg":"trace[601911309] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"534.412685ms","start":"2026-02-22T17:39:54.837614Z","end":"2026-02-22T17:39:55.372027Z","steps":["trace[601911309] 'process raft request' (duration: 534.263964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:55.372189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:54.837586Z","time spent":"534.515856ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:55.372302Z","caller":"traceutil/trace.go:171","msg":"trace[1431921800] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"533.117489ms","start":"2026-02-22T17:39:54.83917Z","end":"2026-02-22T17:39:55.372287Z","steps":["trace[1431921800] 'process raft request' (duration: 532.825828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:55.372381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:54.839149Z","time spent":"533.19475ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:55.372463Z","caller":"traceutil/trace.go:171","msg":"trace[148113363] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"533.013509ms","start":"2026-02-22T17:39:54.839441Z","end":"2026-02-22T17:39:55.372455Z","steps":["trace[148113363] 'process raft request' (duration: 532.799218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:55.372515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:54.839427Z","time spent":"533.064319ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:39:56.12683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.194397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:39:57.623561Z","caller":"traceutil/trace.go:171","msg":"trace[802877263] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"244.971089ms","start":"2026-02-22T17:39:57.378566Z","end":"2026-02-22T17:39:57.623537Z","steps":["trace[802877263] 'read index received' (duration: 244.797848ms)","trace[802877263] 'applied index is now lower than readState.Index' (duration: 172.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:57.623693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.106639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:39:57.623675Z","caller":"traceutil/trace.go:171","msg":"trace[1476439200] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"507.622678ms","start":"2026-02-22T17:39:57.116026Z","end":"2026-02-22T17:39:57.623648Z","steps":["trace[1476439200] 'process raft request' (duration: 507.345607ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:39:57.623725Z","caller":"traceutil/trace.go:171","msg":"trace[1453433623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"245.19415ms","start":"2026-02-22T17:39:57.378523Z","end":"2026-02-22T17:39:57.623718Z","steps":["trace[1453433623] 'agreement among raft nodes before linearized reading' (duration: 245.122899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:57.623761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.093729ms","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-22T17:39:57.623794Z","caller":"traceutil/trace.go:171","msg":"trace[1230290324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"245.1749ms","start":"2026-02-22T17:39:57.378607Z","end":"2026-02-22T17:39:57.623782Z","steps":["trace[1230290324] 'agreement among raft nodes before linearized reading' (duration: 245.052909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:57.623852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:57.116004Z","time spent":"507.746089ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:39:57.623885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.044699ms","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-22T17:39:57.623977Z","caller":"traceutil/trace.go:171","msg":"trace[680057115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"245.17679ms","start":"2026-02-22T17:39:57.37878Z","end":"2026-02-22T17:39:57.623957Z","steps":["trace[680057115] 'agreement among raft nodes before linearized reading' (duration: 244.913079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:57.624002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.17197ms","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-22T17:39:57.624096Z","caller":"traceutil/trace.go:171","msg":"trace[867838807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"245.3124ms","start":"2026-02-22T17:39:57.378765Z","end":"2026-02-22T17:39:57.624077Z","steps":["trace[867838807] 'agreement among raft nodes before linearized reading' (duration: 244.987479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:59.341412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.71349129s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-22T17:39:59.464059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.330946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:39:59.464516Z","caller":"traceutil/trace.go:171","msg":"trace[1407602917] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.83663375s","start":"2026-02-22T17:39:57.627865Z","end":"2026-02-22T17:39:59.464498Z","steps":["trace[1407602917] 'process raft request' (duration: 1.713791452s)","trace[1407602917] 'compare' (duration: 122.097685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:39:59.464566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:57.627851Z","time spent":"1.8366902s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:59.464657Z","caller":"traceutil/trace.go:171","msg":"trace[1267194901] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.836649089s","start":"2026-02-22T17:39:57.628Z","end":"2026-02-22T17:39:59.464649Z","steps":["trace[1267194901] 'process raft request' (duration: 1.836152097s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:59.464693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:57.627984Z","time spent":"1.8366887s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:39:59.464805Z","caller":"traceutil/trace.go:171","msg":"trace[584216725] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.836215789s","start":"2026-02-22T17:39:57.628574Z","end":"2026-02-22T17:39:59.464789Z","steps":["trace[584216725] 'process raft request' (duration: 1.835630775s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:39:59.464865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:39:57.628568Z","time spent":"1.836268299s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:00.771259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:00.460222Z","time spent":"311.029861ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-22T17:40:01.87912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720016917376,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:40:01.994214Z","caller":"traceutil/trace.go:171","msg":"trace[1020355057] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"615.371827ms","start":"2026-02-22T17:40:01.378817Z","end":"2026-02-22T17:40:01.994189Z","steps":["trace[1020355057] 'read index received' (duration: 615.233517ms)","trace[1020355057] 'applied index is now lower than readState.Index' (duration: 135.8µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:01.994328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.512029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:40:01.994354Z","caller":"traceutil/trace.go:171","msg":"trace[1043735759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"615.588249ms","start":"2026-02-22T17:40:01.37876Z","end":"2026-02-22T17:40:01.994348Z","steps":["trace[1043735759] 'agreement among raft nodes before linearized reading' (duration: 615.522459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.994381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.378736Z","time spent":"615.639669ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:40:01.994599Z","caller":"traceutil/trace.go:171","msg":"trace[416059333] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"658.382243ms","start":"2026-02-22T17:40:01.336186Z","end":"2026-02-22T17:40:01.994569Z","steps":["trace[416059333] 'process raft request' (duration: 657.91099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.994657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.737465ms","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-22T17:40:01.994686Z","caller":"traceutil/trace.go:171","msg":"trace[1567718094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"526.800426ms","start":"2026-02-22T17:40:01.467879Z","end":"2026-02-22T17:40:01.99468Z","steps":["trace[1567718094] 'agreement among raft nodes before linearized reading' (duration: 526.716356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.994709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.467862Z","time spent":"526.843196ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:40:01.994736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.336166Z","time spent":"658.497933ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17152,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:01.99486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.615486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:40:01.994885Z","caller":"traceutil/trace.go:171","msg":"trace[1658841819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"356.667986ms","start":"2026-02-22T17:40:01.638211Z","end":"2026-02-22T17:40:01.994879Z","steps":["trace[1658841819] 'agreement among raft nodes before linearized reading' (duration: 356.607886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.994909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.638194Z","time spent":"356.708016ms","remote":"127.0.0.1:34910","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-22T17:40:01.995017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.463101ms","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-22T17:40:01.995039Z","caller":"traceutil/trace.go:171","msg":"trace[1345015494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"525.497261ms","start":"2026-02-22T17:40:01.469536Z","end":"2026-02-22T17:40:01.995033Z","steps":["trace[1345015494] 'agreement among raft nodes before linearized reading' (duration: 525.446321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.995056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.469526Z","time spent":"525.526601ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:40:01.99516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.152533ms","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-22T17:40:01.995181Z","caller":"traceutil/trace.go:171","msg":"trace[1352912106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"526.236373ms","start":"2026-02-22T17:40:01.46894Z","end":"2026-02-22T17:40:01.995176Z","steps":["trace[1352912106] 'agreement among raft nodes before linearized reading' (duration: 526.182283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:01.995197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:01.468922Z","time spent":"526.271883ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:40:04.060847Z","caller":"traceutil/trace.go:171","msg":"trace[862930998] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"459.125341ms","start":"2026-02-22T17:40:03.601666Z","end":"2026-02-22T17:40:04.060791Z","steps":["trace[862930998] 'process raft request' (duration: 458.91472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:04.061143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:03.601633Z","time spent":"459.354762ms","remote":"127.0.0.1:34726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:40:04.068836Z","caller":"traceutil/trace.go:171","msg":"trace[552792527] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"267.231525ms","start":"2026-02-22T17:40:03.801584Z","end":"2026-02-22T17:40:04.068816Z","steps":["trace[552792527] 'process raft request' (duration: 266.941154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:05.70436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.161998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-22T17:40:05.704472Z","caller":"traceutil/trace.go:171","msg":"trace[1821882615] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:584; }","duration":"106.327918ms","start":"2026-02-22T17:40:05.598118Z","end":"2026-02-22T17:40:05.704446Z","steps":["trace[1821882615] 'range keys from in-memory index tree' (duration: 105.932776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:07.524773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.634051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6xm77\" ","response":"range_response_count:1 size:4696"} {"level":"warn","ts":"2026-02-22T17:40:07.524805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.556561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-c9rj7\" ","response":"range_response_count:1 size:3795"} {"level":"warn","ts":"2026-02-22T17:40:07.524813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.650672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tsllz\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-22T17:40:07.524863Z","caller":"traceutil/trace.go:171","msg":"trace[2023945492] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-6xm77; range_end:; response_count:1; response_revision:612; }","duration":"129.742942ms","start":"2026-02-22T17:40:07.395099Z","end":"2026-02-22T17:40:07.524842Z","steps":["trace[2023945492] 'agreement among raft nodes before linearized reading' (duration: 129.533641ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:07.524883Z","caller":"traceutil/trace.go:171","msg":"trace[770132890] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-c9rj7; range_end:; response_count:1; response_revision:612; }","duration":"129.670192ms","start":"2026-02-22T17:40:07.39519Z","end":"2026-02-22T17:40:07.524861Z","steps":["trace[770132890] 'agreement among raft nodes before linearized reading' (duration: 129.396071ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:07.524892Z","caller":"traceutil/trace.go:171","msg":"trace[205098681] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-tsllz; range_end:; response_count:1; response_revision:612; }","duration":"129.746482ms","start":"2026-02-22T17:40:07.395125Z","end":"2026-02-22T17:40:07.524871Z","steps":["trace[205098681] 'agreement among raft nodes before linearized reading' (duration: 129.510811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:07.525094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.022832ms","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-22T17:40:07.525133Z","caller":"traceutil/trace.go:171","msg":"trace[93395997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:612; }","duration":"103.080582ms","start":"2026-02-22T17:40:07.422043Z","end":"2026-02-22T17:40:07.525123Z","steps":["trace[93395997] 'agreement among raft nodes before linearized reading' (duration: 102.991422ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:07.879969Z","caller":"traceutil/trace.go:171","msg":"trace[1796046628] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"171.126898ms","start":"2026-02-22T17:40:07.708806Z","end":"2026-02-22T17:40:07.879933Z","steps":["trace[1796046628] 'process raft request' (duration: 167.416921ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:07.880046Z","caller":"traceutil/trace.go:171","msg":"trace[1367647399] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"132.292743ms","start":"2026-02-22T17:40:07.747724Z","end":"2026-02-22T17:40:07.880017Z","steps":["trace[1367647399] 'process raft request' (duration: 132.057181ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:14.354201Z","caller":"traceutil/trace.go:171","msg":"trace[526830646] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"141.737698ms","start":"2026-02-22T17:40:14.212446Z","end":"2026-02-22T17:40:14.354184Z","steps":["trace[526830646] 'read index received' (duration: 141.573498ms)","trace[526830646] 'applied index is now lower than readState.Index' (duration: 163.43µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:14.354259Z","caller":"traceutil/trace.go:171","msg":"trace[404105580] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"149.121522ms","start":"2026-02-22T17:40:14.205106Z","end":"2026-02-22T17:40:14.354228Z","steps":["trace[404105580] 'process raft request' (duration: 148.903461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:14.354391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.934829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-22T17:40:14.35442Z","caller":"traceutil/trace.go:171","msg":"trace[1424138086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"142.008ms","start":"2026-02-22T17:40:14.212402Z","end":"2026-02-22T17:40:14.35441Z","steps":["trace[1424138086] 'agreement among raft nodes before linearized reading' (duration: 141.89588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:14.354518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.261119ms","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-22T17:40:14.354581Z","caller":"traceutil/trace.go:171","msg":"trace[1598205460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"137.351179ms","start":"2026-02-22T17:40:14.217214Z","end":"2026-02-22T17:40:14.354566Z","steps":["trace[1598205460] 'agreement among raft nodes before linearized reading' (duration: 137.182828ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:28.721024Z","caller":"traceutil/trace.go:171","msg":"trace[1527050148] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"266.738765ms","start":"2026-02-22T17:40:28.45426Z","end":"2026-02-22T17:40:28.720999Z","steps":["trace[1527050148] 'process raft request' (duration: 266.614104ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:28.831316Z","caller":"traceutil/trace.go:171","msg":"trace[156792063] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"376.406605ms","start":"2026-02-22T17:40:28.454871Z","end":"2026-02-22T17:40:28.831277Z","steps":["trace[156792063] 'process raft request' (duration: 303.588847ms)","trace[156792063] 'compare' (duration: 72.493806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:28.831518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:28.454859Z","time spent":"376.547165ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:40:28.831495Z","caller":"traceutil/trace.go:171","msg":"trace[1478042627] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"376.441135ms","start":"2026-02-22T17:40:28.455031Z","end":"2026-02-22T17:40:28.831473Z","steps":["trace[1478042627] 'process raft request' (duration: 376.373894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:28.831847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:28.455005Z","time spent":"376.727026ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:40:30.781179Z","caller":"traceutil/trace.go:171","msg":"trace[419204657] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"175.482996ms","start":"2026-02-22T17:40:30.605665Z","end":"2026-02-22T17:40:30.781148Z","steps":["trace[419204657] 'process raft request' (duration: 175.281835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:31.202864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.17517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:31.202964Z","caller":"traceutil/trace.go:171","msg":"trace[107211087] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"366.981319ms","start":"2026-02-22T17:40:30.835967Z","end":"2026-02-22T17:40:31.202948Z","steps":["trace[107211087] 'read index received' (duration: 24.662998ms)","trace[107211087] 'applied index is now lower than readState.Index' (duration: 342.316891ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:31.203031Z","caller":"traceutil/trace.go:171","msg":"trace[767114538] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"417.7791ms","start":"2026-02-22T17:40:30.785217Z","end":"2026-02-22T17:40:31.202996Z","steps":["trace[767114538] 'process raft request' (duration: 75.393859ms)","trace[767114538] 'compare' (duration: 342.06318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:31.203127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.16485ms","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-22T17:40:31.203157Z","caller":"traceutil/trace.go:171","msg":"trace[1337096860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"367.23248ms","start":"2026-02-22T17:40:30.835915Z","end":"2026-02-22T17:40:31.203147Z","steps":["trace[1337096860] 'agreement among raft nodes before linearized reading' (duration: 367.113909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:31.203199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:30.835896Z","time spent":"367.29553ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:40:31.203232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:30.785197Z","time spent":"417.937751ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:31.203789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.811013ms","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-22T17:40:31.203885Z","caller":"traceutil/trace.go:171","msg":"trace[1178470670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"367.948964ms","start":"2026-02-22T17:40:30.83592Z","end":"2026-02-22T17:40:31.203869Z","steps":["trace[1178470670] 'agreement among raft nodes before linearized reading' (duration: 367.23977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:31.203933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:30.835903Z","time spent":"368.014944ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:40:33.436523Z","caller":"traceutil/trace.go:171","msg":"trace[1224107510] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"179.154619ms","start":"2026-02-22T17:40:33.257343Z","end":"2026-02-22T17:40:33.436498Z","steps":["trace[1224107510] 'read index received' (duration: 179.066059ms)","trace[1224107510] 'applied index is now lower than readState.Index' (duration: 87.38µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:33.436576Z","caller":"traceutil/trace.go:171","msg":"trace[1466846222] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"225.514451ms","start":"2026-02-22T17:40:33.21103Z","end":"2026-02-22T17:40:33.436545Z","steps":["trace[1466846222] 'process raft request' (duration: 225.309641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:33.437211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.818982ms","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-22T17:40:33.437329Z","caller":"traceutil/trace.go:171","msg":"trace[1577420452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"179.962062ms","start":"2026-02-22T17:40:33.257296Z","end":"2026-02-22T17:40:33.437259Z","steps":["trace[1577420452] 'agreement among raft nodes before linearized reading' (duration: 179.34142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:33.437871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.526265ms","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-22T17:40:33.437964Z","caller":"traceutil/trace.go:171","msg":"trace[515525703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"134.627106ms","start":"2026-02-22T17:40:33.303279Z","end":"2026-02-22T17:40:33.437906Z","steps":["trace[515525703] 'agreement among raft nodes before linearized reading' (duration: 134.477815ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:35.615498Z","caller":"traceutil/trace.go:171","msg":"trace[1675232072] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"166.675284ms","start":"2026-02-22T17:40:35.448779Z","end":"2026-02-22T17:40:35.615455Z","steps":["trace[1675232072] 'read index received' (duration: 166.575753ms)","trace[1675232072] 'applied index is now lower than readState.Index' (duration: 97.861µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:35.615523Z","caller":"traceutil/trace.go:171","msg":"trace[350450683] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"169.482726ms","start":"2026-02-22T17:40:35.446003Z","end":"2026-02-22T17:40:35.615486Z","steps":["trace[350450683] 'process raft request' (duration: 169.248005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:35.615791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.896295ms","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-22T17:40:35.615887Z","caller":"traceutil/trace.go:171","msg":"trace[800541877] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:760; }","duration":"167.112186ms","start":"2026-02-22T17:40:35.448758Z","end":"2026-02-22T17:40:35.61587Z","steps":["trace[800541877] 'agreement among raft nodes before linearized reading' (duration: 166.856154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:35.872572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.672063ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:40:35.872682Z","caller":"traceutil/trace.go:171","msg":"trace[1490365955] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"257.081727ms","start":"2026-02-22T17:40:35.615582Z","end":"2026-02-22T17:40:35.872664Z","steps":["trace[1490365955] 'read index received' (duration: 44.311703ms)","trace[1490365955] 'applied index is now lower than readState.Index' (duration: 212.768464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:35.872899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.135326ms","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-22T17:40:35.87291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.191986ms","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-22T17:40:35.872975Z","caller":"traceutil/trace.go:171","msg":"trace[1312103736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"342.248346ms","start":"2026-02-22T17:40:35.53071Z","end":"2026-02-22T17:40:35.872959Z","steps":["trace[1312103736] 'agreement among raft nodes before linearized reading' (duration: 342.007585ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:35.872977Z","caller":"traceutil/trace.go:171","msg":"trace[82348601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"342.307446ms","start":"2026-02-22T17:40:35.530657Z","end":"2026-02-22T17:40:35.872964Z","steps":["trace[82348601] 'agreement among raft nodes before linearized reading' (duration: 342.063155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:35.873053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:35.530691Z","time spent":"342.351726ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T17:40:35.873066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:35.530624Z","time spent":"342.431716ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:40:36.303607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.808194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:36.303734Z","caller":"traceutil/trace.go:171","msg":"trace[775560877] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"287.394857ms","start":"2026-02-22T17:40:36.016326Z","end":"2026-02-22T17:40:36.303721Z","steps":["trace[775560877] 'read index received' (duration: 340.632µs)","trace[775560877] 'applied index is now lower than readState.Index' (duration: 287.053205ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:36.303836Z","caller":"traceutil/trace.go:171","msg":"trace[1253236546] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"425.108915ms","start":"2026-02-22T17:40:35.878699Z","end":"2026-02-22T17:40:36.303808Z","steps":["trace[1253236546] 'process raft request' (duration: 138.02659ms)","trace[1253236546] 'compare' (duration: 286.691804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:36.303883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.555108ms","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-22T17:40:36.303906Z","caller":"traceutil/trace.go:171","msg":"trace[203613767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"287.617549ms","start":"2026-02-22T17:40:36.01628Z","end":"2026-02-22T17:40:36.303898Z","steps":["trace[203613767] 'agreement among raft nodes before linearized reading' (duration: 287.476148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:36.303979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:35.878686Z","time spent":"425.217106ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:36.58952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.737043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:36.589657Z","caller":"traceutil/trace.go:171","msg":"trace[440293823] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"211.367627ms","start":"2026-02-22T17:40:36.378268Z","end":"2026-02-22T17:40:36.589636Z","steps":["trace[440293823] 'read index received' (duration: 35.379993ms)","trace[440293823] 'applied index is now lower than readState.Index' (duration: 175.986094ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:36.590012Z","caller":"traceutil/trace.go:171","msg":"trace[1430915418] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"280.831519ms","start":"2026-02-22T17:40:36.309163Z","end":"2026-02-22T17:40:36.589995Z","steps":["trace[1430915418] 'process raft request' (duration: 104.522714ms)","trace[1430915418] 'compare' (duration: 175.608902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:36.590055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.771288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:40:36.590113Z","caller":"traceutil/trace.go:171","msg":"trace[234171983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"211.858248ms","start":"2026-02-22T17:40:36.378241Z","end":"2026-02-22T17:40:36.590099Z","steps":["trace[234171983] 'agreement among raft nodes before linearized reading' (duration: 211.768148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:36.590159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.218441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T17:40:36.590225Z","caller":"traceutil/trace.go:171","msg":"trace[766933078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"106.356652ms","start":"2026-02-22T17:40:36.483853Z","end":"2026-02-22T17:40:36.590209Z","steps":["trace[766933078] 'agreement among raft nodes before linearized reading' (duration: 106.159721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:37.030283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.903334ms","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-22T17:40:37.030492Z","caller":"traceutil/trace.go:171","msg":"trace[1438257973] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:763; }","duration":"250.124346ms","start":"2026-02-22T17:40:36.78035Z","end":"2026-02-22T17:40:37.030474Z","steps":["trace[1438257973] 'range keys from in-memory index tree' (duration: 249.889434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:37.030548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.159878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:37.03063Z","caller":"traceutil/trace.go:171","msg":"trace[2059976229] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"436.453375ms","start":"2026-02-22T17:40:36.594163Z","end":"2026-02-22T17:40:37.030616Z","steps":["trace[2059976229] 'process raft request' (duration: 181.146016ms)","trace[2059976229] 'compare' (duration: 254.841236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:37.030708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:36.594135Z","time spent":"436.530595ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:38.447932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720016917877,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:40:38.481858Z","caller":"traceutil/trace.go:171","msg":"trace[480736865] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"534.637628ms","start":"2026-02-22T17:40:37.947194Z","end":"2026-02-22T17:40:38.481832Z","steps":["trace[480736865] 'read index received' (duration: 534.392077ms)","trace[480736865] 'applied index is now lower than readState.Index' (duration: 244.771µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:38.481928Z","caller":"traceutil/trace.go:171","msg":"trace[1131330127] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"851.748382ms","start":"2026-02-22T17:40:37.630152Z","end":"2026-02-22T17:40:38.481901Z","steps":["trace[1131330127] 'process raft request' (duration: 851.497441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:38.482063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:37.630136Z","time spent":"851.842062ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:38.48207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.033689ms","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-22T17:40:38.482084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.874918ms","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-22T17:40:38.482121Z","caller":"traceutil/trace.go:171","msg":"trace[816740416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"175.13541ms","start":"2026-02-22T17:40:38.306975Z","end":"2026-02-22T17:40:38.482111Z","steps":["trace[816740416] 'agreement among raft nodes before linearized reading' (duration: 174.978279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:38.482071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.101976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:40:38.482155Z","caller":"traceutil/trace.go:171","msg":"trace[1389343469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"534.992759ms","start":"2026-02-22T17:40:37.947144Z","end":"2026-02-22T17:40:38.482136Z","steps":["trace[1389343469] 'agreement among raft nodes before linearized reading' (duration: 534.780628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:38.482215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:37.947123Z","time spent":"535.084159ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:40:38.482228Z","caller":"traceutil/trace.go:171","msg":"trace[1275030947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"105.277527ms","start":"2026-02-22T17:40:38.376909Z","end":"2026-02-22T17:40:38.482186Z","steps":["trace[1275030947] 'agreement among raft nodes before linearized reading' (duration: 105.110976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:38.803986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.474579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:38.804138Z","caller":"traceutil/trace.go:171","msg":"trace[378438555] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"318.692161ms","start":"2026-02-22T17:40:38.485427Z","end":"2026-02-22T17:40:38.804119Z","steps":["trace[378438555] 'process raft request' (duration: 97.007841ms)","trace[378438555] 'compare' (duration: 221.357429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:38.804208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:38.485406Z","time spent":"318.771861ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:40:38.804315Z","caller":"traceutil/trace.go:171","msg":"trace[1791987888] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"208.905826ms","start":"2026-02-22T17:40:38.595384Z","end":"2026-02-22T17:40:38.80429Z","steps":["trace[1791987888] 'read index received' (duration: 86.006993ms)","trace[1791987888] 'applied index is now lower than readState.Index' (duration: 122.897583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:38.804524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.121527ms","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-22T17:40:38.804573Z","caller":"traceutil/trace.go:171","msg":"trace[462735724] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"316.964434ms","start":"2026-02-22T17:40:38.487598Z","end":"2026-02-22T17:40:38.804562Z","steps":["trace[462735724] 'process raft request' (duration: 316.485962ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:38.804604Z","caller":"traceutil/trace.go:171","msg":"trace[277027627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"209.216637ms","start":"2026-02-22T17:40:38.595337Z","end":"2026-02-22T17:40:38.804554Z","steps":["trace[277027627] 'agreement among raft nodes before linearized reading' (duration: 209.044896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:38.804641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:38.487571Z","time spent":"317.038894ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:40:39.245561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.456168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:40:39.245669Z","caller":"traceutil/trace.go:171","msg":"trace[1272342012] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"211.745086ms","start":"2026-02-22T17:40:39.033909Z","end":"2026-02-22T17:40:39.245654Z","steps":["trace[1272342012] 'read index received' (duration: 40.48µs)","trace[1272342012] 'applied index is now lower than readState.Index' (duration: 211.702106ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:39.246044Z","caller":"traceutil/trace.go:171","msg":"trace[146087398] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"436.940883ms","start":"2026-02-22T17:40:38.809082Z","end":"2026-02-22T17:40:39.246023Z","steps":["trace[146087398] 'process raft request' (duration: 131.933213ms)","trace[146087398] 'compare' (duration: 304.325047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:39.246232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.317449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-22T17:40:39.24628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:38.809061Z","time spent":"437.044943ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:40:39.246328Z","caller":"traceutil/trace.go:171","msg":"trace[900179501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"212.41515ms","start":"2026-02-22T17:40:39.033897Z","end":"2026-02-22T17:40:39.246312Z","steps":["trace[900179501] 'agreement among raft nodes before linearized reading' (duration: 212.196198ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:40:39.567252Z","caller":"traceutil/trace.go:171","msg":"trace[454869157] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"189.316839ms","start":"2026-02-22T17:40:39.377903Z","end":"2026-02-22T17:40:39.56722Z","steps":["trace[454869157] 'read index received' (duration: 121.484906ms)","trace[454869157] 'applied index is now lower than readState.Index' (duration: 67.831053ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:40:39.567376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.462191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:40:39.567351Z","caller":"traceutil/trace.go:171","msg":"trace[572560753] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"317.025923ms","start":"2026-02-22T17:40:39.250284Z","end":"2026-02-22T17:40:39.56731Z","steps":["trace[572560753] 'process raft request' (duration: 249.095399ms)","trace[572560753] 'compare' (duration: 67.731393ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:40:39.567417Z","caller":"traceutil/trace.go:171","msg":"trace[944041633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"189.559851ms","start":"2026-02-22T17:40:39.377847Z","end":"2026-02-22T17:40:39.567406Z","steps":["trace[944041633] 'agreement among raft nodes before linearized reading' (duration: 189.478161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:40:39.567532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:40:39.250265Z","time spent":"317.172503ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:41:07.125253Z","caller":"traceutil/trace.go:171","msg":"trace[1050202984] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"101.300114ms","start":"2026-02-22T17:41:07.023929Z","end":"2026-02-22T17:41:07.125229Z","steps":["trace[1050202984] 'read index received' (duration: 101.169154ms)","trace[1050202984] 'applied index is now lower than readState.Index' (duration: 130.03µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:07.12534Z","caller":"traceutil/trace.go:171","msg":"trace[1856841158] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"114.49896ms","start":"2026-02-22T17:41:07.01081Z","end":"2026-02-22T17:41:07.125309Z","steps":["trace[1856841158] 'process raft request' (duration: 114.283229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:07.125538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.569975ms","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-22T17:41:07.12564Z","caller":"traceutil/trace.go:171","msg":"trace[308600541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:845; }","duration":"101.691366ms","start":"2026-02-22T17:41:07.023924Z","end":"2026-02-22T17:41:07.125615Z","steps":["trace[308600541] 'agreement among raft nodes before linearized reading' (duration: 101.426605ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:08.05448Z","caller":"traceutil/trace.go:171","msg":"trace[290618142] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"308.854162ms","start":"2026-02-22T17:41:07.745596Z","end":"2026-02-22T17:41:08.054451Z","steps":["trace[290618142] 'read index received' (duration: 308.55404ms)","trace[290618142] 'applied index is now lower than readState.Index' (duration: 298.862µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:08.054546Z","caller":"traceutil/trace.go:171","msg":"trace[1922838825] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"323.463894ms","start":"2026-02-22T17:41:07.731049Z","end":"2026-02-22T17:41:08.054513Z","steps":["trace[1922838825] 'process raft request' (duration: 323.182553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:08.054697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:07.731024Z","time spent":"323.590024ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:41:08.054726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.121193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-22T17:41:08.054788Z","caller":"traceutil/trace.go:171","msg":"trace[1845330687] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:848; }","duration":"309.222913ms","start":"2026-02-22T17:41:07.745552Z","end":"2026-02-22T17:41:08.054775Z","steps":["trace[1845330687] 'agreement among raft nodes before linearized reading' (duration: 309.022722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:08.054825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:07.745536Z","time spent":"309.280503ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-22T17:41:22.048586Z","caller":"traceutil/trace.go:171","msg":"trace[1135886979] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"463.88051ms","start":"2026-02-22T17:41:21.584683Z","end":"2026-02-22T17:41:22.048563Z","steps":["trace[1135886979] 'process raft request' (duration: 463.74169ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:22.048635Z","caller":"traceutil/trace.go:171","msg":"trace[1158647072] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:955; }","duration":"196.313158ms","start":"2026-02-22T17:41:21.85229Z","end":"2026-02-22T17:41:22.048604Z","steps":["trace[1158647072] 'read index received' (duration: 196.302278ms)","trace[1158647072] 'applied index is now lower than readState.Index' (duration: 9.07µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:41:22.04875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:21.584659Z","time spent":"464.010231ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:41:22.048794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.468969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:41:22.048835Z","caller":"traceutil/trace.go:171","msg":"trace[1657710365] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:917; }","duration":"196.585059ms","start":"2026-02-22T17:41:21.85224Z","end":"2026-02-22T17:41:22.048825Z","steps":["trace[1657710365] 'agreement among raft nodes before linearized reading' (duration: 196.467569ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:29.583309Z","caller":"traceutil/trace.go:171","msg":"trace[808673292] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"207.164483ms","start":"2026-02-22T17:41:29.37612Z","end":"2026-02-22T17:41:29.583285Z","steps":["trace[808673292] 'read index received' (duration: 206.918501ms)","trace[808673292] 'applied index is now lower than readState.Index' (duration: 245.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:29.583643Z","caller":"traceutil/trace.go:171","msg":"trace[1277717594] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"335.28781ms","start":"2026-02-22T17:41:29.248341Z","end":"2026-02-22T17:41:29.583629Z","steps":["trace[1277717594] 'process raft request' (duration: 334.786657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:29.583778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:29.248323Z","time spent":"335.35745ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:41:29.583981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.417026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:41:29.58411Z","caller":"traceutil/trace.go:171","msg":"trace[2129369928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"205.573257ms","start":"2026-02-22T17:41:29.378519Z","end":"2026-02-22T17:41:29.584092Z","steps":["trace[2129369928] 'agreement among raft nodes before linearized reading' (duration: 205.397226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:29.584206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.081827ms","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-22T17:41:29.584252Z","caller":"traceutil/trace.go:171","msg":"trace[428223302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"208.160297ms","start":"2026-02-22T17:41:29.376078Z","end":"2026-02-22T17:41:29.584239Z","steps":["trace[428223302] 'agreement among raft nodes before linearized reading' (duration: 208.032907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:30.689545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720016918389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:41:30.759505Z","caller":"traceutil/trace.go:171","msg":"trace[877604760] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"570.29691ms","start":"2026-02-22T17:41:30.189182Z","end":"2026-02-22T17:41:30.759478Z","steps":["trace[877604760] 'read index received' (duration: 570.063579ms)","trace[877604760] 'applied index is now lower than readState.Index' (duration: 232.291µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:30.759557Z","caller":"traceutil/trace.go:171","msg":"trace[475732309] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"671.197971ms","start":"2026-02-22T17:41:30.088335Z","end":"2026-02-22T17:41:30.759533Z","steps":["trace[475732309] 'process raft request' (duration: 670.99627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:30.759724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.539231ms","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-22T17:41:30.759763Z","caller":"traceutil/trace.go:171","msg":"trace[375140319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"570.635672ms","start":"2026-02-22T17:41:30.189117Z","end":"2026-02-22T17:41:30.759753Z","steps":["trace[375140319] 'agreement among raft nodes before linearized reading' (duration: 570.491081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:30.759736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:30.088314Z","time spent":"671.300081ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:41:30.759794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:30.189095Z","time spent":"570.692563ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:41:30.760179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.853258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-22T17:41:30.760348Z","caller":"traceutil/trace.go:171","msg":"trace[317948160] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:946; }","duration":"295.039398ms","start":"2026-02-22T17:41:30.46524Z","end":"2026-02-22T17:41:30.760279Z","steps":["trace[317948160] 'agreement among raft nodes before linearized reading' (duration: 294.582317ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:33.836012Z","caller":"traceutil/trace.go:171","msg":"trace[1432311504] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"198.028894ms","start":"2026-02-22T17:41:33.637951Z","end":"2026-02-22T17:41:33.83598Z","steps":["trace[1432311504] 'process raft request' (duration: 128.019305ms)","trace[1432311504] 'compare' (duration: 69.856228ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:34.927741Z","caller":"traceutil/trace.go:171","msg":"trace[1206011984] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"229.365358ms","start":"2026-02-22T17:41:34.698354Z","end":"2026-02-22T17:41:34.92772Z","steps":["trace[1206011984] 'read index received' (duration: 229.179558ms)","trace[1206011984] 'applied index is now lower than readState.Index' (duration: 184.86µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:34.927811Z","caller":"traceutil/trace.go:171","msg":"trace[1403130790] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"249.425394ms","start":"2026-02-22T17:41:34.678357Z","end":"2026-02-22T17:41:34.927782Z","steps":["trace[1403130790] 'process raft request' (duration: 249.228693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:34.92807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.70082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-22T17:41:34.92813Z","caller":"traceutil/trace.go:171","msg":"trace[624000705] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:962; }","duration":"229.776731ms","start":"2026-02-22T17:41:34.698337Z","end":"2026-02-22T17:41:34.928114Z","steps":["trace[624000705] 'agreement among raft nodes before linearized reading' (duration: 229.546009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:34.928201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.70258ms","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-22T17:41:34.92828Z","caller":"traceutil/trace.go:171","msg":"trace[1861941495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"154.911782ms","start":"2026-02-22T17:41:34.773355Z","end":"2026-02-22T17:41:34.928267Z","steps":["trace[1861941495] 'agreement among raft nodes before linearized reading' (duration: 154.600419ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:35.113858Z","caller":"traceutil/trace.go:171","msg":"trace[926082374] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"181.490493ms","start":"2026-02-22T17:41:34.932338Z","end":"2026-02-22T17:41:35.113828Z","steps":["trace[926082374] 'process raft request' (duration: 146.666154ms)","trace[926082374] 'compare' (duration: 34.667408ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:35.11554Z","caller":"traceutil/trace.go:171","msg":"trace[328676142] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1002; }","duration":"138.205029ms","start":"2026-02-22T17:41:34.977312Z","end":"2026-02-22T17:41:35.115517Z","steps":["trace[328676142] 'read index received' (duration: 101.672893ms)","trace[328676142] 'applied index is now lower than readState.Index' (duration: 36.531195ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:35.115586Z","caller":"traceutil/trace.go:171","msg":"trace[698760608] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"183.16214ms","start":"2026-02-22T17:41:34.932403Z","end":"2026-02-22T17:41:35.115565Z","steps":["trace[698760608] 'process raft request' (duration: 182.953689ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:35.115729Z","caller":"traceutil/trace.go:171","msg":"trace[682147514] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"183.29441ms","start":"2026-02-22T17:41:34.932424Z","end":"2026-02-22T17:41:35.115719Z","steps":["trace[682147514] 'process raft request' (duration: 183.052739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:35.115794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.465089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-22T17:41:35.115841Z","caller":"traceutil/trace.go:171","msg":"trace[2083838618] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:965; }","duration":"138.572041ms","start":"2026-02-22T17:41:34.977256Z","end":"2026-02-22T17:41:35.115828Z","steps":["trace[2083838618] 'agreement among raft nodes before linearized reading' (duration: 138.349879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:35.896051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.89313ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:41:35.896879Z","caller":"traceutil/trace.go:171","msg":"trace[1981084596] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"259.780877ms","start":"2026-02-22T17:41:35.637077Z","end":"2026-02-22T17:41:35.896858Z","steps":["trace[1981084596] 'read index received' (duration: 57.077113ms)","trace[1981084596] 'applied index is now lower than readState.Index' (duration: 202.702084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:41:35.897115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.018218ms","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-22T17:41:35.897192Z","caller":"traceutil/trace.go:171","msg":"trace[1288579275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"260.138608ms","start":"2026-02-22T17:41:35.637036Z","end":"2026-02-22T17:41:35.897175Z","steps":["trace[1288579275] 'agreement among raft nodes before linearized reading' (duration: 259.940967ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:36.095489Z","caller":"traceutil/trace.go:171","msg":"trace[1664331343] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"194.358008ms","start":"2026-02-22T17:41:35.901106Z","end":"2026-02-22T17:41:36.095464Z","steps":["trace[1664331343] 'process raft request' (duration: 104.590915ms)","trace[1664331343] 'compare' (duration: 89.658862ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:40.636572Z","caller":"traceutil/trace.go:171","msg":"trace[1752119458] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"141.197981ms","start":"2026-02-22T17:41:40.495308Z","end":"2026-02-22T17:41:40.636506Z","steps":["trace[1752119458] 'process raft request' (duration: 82.22459ms)","trace[1752119458] 'compare' (duration: 58.82573ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:41.405783Z","caller":"traceutil/trace.go:171","msg":"trace[861209701] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"128.393727ms","start":"2026-02-22T17:41:41.277347Z","end":"2026-02-22T17:41:41.40574Z","steps":["trace[861209701] 'process raft request' (duration: 127.342702ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:41.408797Z","caller":"traceutil/trace.go:171","msg":"trace[1778382341] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"130.580317ms","start":"2026-02-22T17:41:41.278179Z","end":"2026-02-22T17:41:41.408759Z","steps":["trace[1778382341] 'read index received' (duration: 126.522369ms)","trace[1778382341] 'applied index is now lower than readState.Index' (duration: 4.056718ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:41.408905Z","caller":"traceutil/trace.go:171","msg":"trace[800534658] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"131.165259ms","start":"2026-02-22T17:41:41.277705Z","end":"2026-02-22T17:41:41.408871Z","steps":["trace[800534658] 'process raft request' (duration: 130.927888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:41.408993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.777607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-02-22T17:41:41.409097Z","caller":"traceutil/trace.go:171","msg":"trace[1084502643] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:981; }","duration":"130.903818ms","start":"2026-02-22T17:41:41.278166Z","end":"2026-02-22T17:41:41.40907Z","steps":["trace[1084502643] 'agreement among raft nodes before linearized reading' (duration: 130.710997ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:43.523685Z","caller":"traceutil/trace.go:171","msg":"trace[1324751383] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"105.777571ms","start":"2026-02-22T17:41:43.417883Z","end":"2026-02-22T17:41:43.523661Z","steps":["trace[1324751383] 'process raft request' (duration: 105.52641ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:43.560978Z","caller":"traceutil/trace.go:171","msg":"trace[973849689] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"142.705548ms","start":"2026-02-22T17:41:43.418245Z","end":"2026-02-22T17:41:43.56095Z","steps":["trace[973849689] 'process raft request' (duration: 142.149695ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:47.759533Z","caller":"traceutil/trace.go:171","msg":"trace[1408313142] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"164.252629ms","start":"2026-02-22T17:41:47.595254Z","end":"2026-02-22T17:41:47.759507Z","steps":["trace[1408313142] 'read index received' (duration: 104.143804ms)","trace[1408313142] 'applied index is now lower than readState.Index' (duration: 60.107785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:41:47.75968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.40555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/local-path-storage\" ","response":"range_response_count:1 size:404"} {"level":"info","ts":"2026-02-22T17:41:47.759719Z","caller":"traceutil/trace.go:171","msg":"trace[941539153] range","detail":"{range_begin:/registry/namespaces/local-path-storage; range_end:; response_count:1; response_revision:998; }","duration":"164.48413ms","start":"2026-02-22T17:41:47.595223Z","end":"2026-02-22T17:41:47.759707Z","steps":["trace[941539153] 'agreement among raft nodes before linearized reading' (duration: 164.38888ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:47.75972Z","caller":"traceutil/trace.go:171","msg":"trace[1577163513] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"175.787799ms","start":"2026-02-22T17:41:47.583868Z","end":"2026-02-22T17:41:47.759655Z","steps":["trace[1577163513] 'process raft request' (duration: 117.29917ms)","trace[1577163513] 'compare' (duration: 58.205608ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:41:51.990565Z","caller":"traceutil/trace.go:171","msg":"trace[1721897865] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"356.455346ms","start":"2026-02-22T17:41:51.634087Z","end":"2026-02-22T17:41:51.990542Z","steps":["trace[1721897865] 'read index received' (duration: 356.269835ms)","trace[1721897865] 'applied index is now lower than readState.Index' (duration: 184.251µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:41:51.99072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.604966ms","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-22T17:41:51.990677Z","caller":"traceutil/trace.go:171","msg":"trace[1300701777] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"361.787499ms","start":"2026-02-22T17:41:51.628824Z","end":"2026-02-22T17:41:51.990612Z","steps":["trace[1300701777] 'process raft request' (duration: 361.549928ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:51.990798Z","caller":"traceutil/trace.go:171","msg":"trace[900720033] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1008; }","duration":"356.736608ms","start":"2026-02-22T17:41:51.634045Z","end":"2026-02-22T17:41:51.990781Z","steps":["trace[900720033] 'agreement among raft nodes before linearized reading' (duration: 356.594617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:51.990848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:51.634023Z","time spent":"356.812898ms","remote":"127.0.0.1:34822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-02-22T17:41:51.991096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:41:51.628812Z","time spent":"362.01659ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:41:51.991265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.500969ms","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":"warn","ts":"2026-02-22T17:41:51.991274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.063699ms","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-22T17:41:51.991308Z","caller":"traceutil/trace.go:171","msg":"trace[1784760394] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1008; }","duration":"267.589359ms","start":"2026-02-22T17:41:51.723708Z","end":"2026-02-22T17:41:51.991298Z","steps":["trace[1784760394] 'agreement among raft nodes before linearized reading' (duration: 267.519709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:51.991279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.901901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"info","ts":"2026-02-22T17:41:51.991363Z","caller":"traceutil/trace.go:171","msg":"trace[1966768628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"164.21351ms","start":"2026-02-22T17:41:51.827125Z","end":"2026-02-22T17:41:51.991339Z","steps":["trace[1966768628] 'agreement among raft nodes before linearized reading' (duration: 163.932289ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:41:51.991469Z","caller":"traceutil/trace.go:171","msg":"trace[998200446] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1008; }","duration":"280.092841ms","start":"2026-02-22T17:41:51.711303Z","end":"2026-02-22T17:41:51.991396Z","steps":["trace[998200446] 'agreement among raft nodes before linearized reading' (duration: 279.75226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:41:51.991549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.810258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-02-22T17:41:51.991586Z","caller":"traceutil/trace.go:171","msg":"trace[1096163128] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1008; }","duration":"276.865128ms","start":"2026-02-22T17:41:51.714712Z","end":"2026-02-22T17:41:51.991577Z","steps":["trace[1096163128] 'agreement among raft nodes before linearized reading' (duration: 276.802488ms)"],"step_count":1}