{"level":"warn","ts":"2026-09-26T21:49:35.439579Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-26T21:49:35.439679Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241: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.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241: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-09-26T21:49:35.439762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-26T21:49:35.439778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-26T21:49:35.439829Z","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-09-26T21:49:35.440465Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-09-26T21:49:35.440541Z","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.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241: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-09-26T21:49:35.443513Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.784144ms"} {"level":"info","ts":"2026-09-26T21:49:35.455214Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-09-26T21:49:35.455307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-09-26T21:49:35.455328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-09-26T21:49:35.455339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-26T21:49:35.45535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-09-26T21:49:35.455386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-09-26T21:49:35.461822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-26T21:49:35.463471Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-26T21:49:35.464852Z","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-09-26T21:49:35.46696Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-26T21:49:35.467118Z","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-09-26T21:49:35.468431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-26T21:49:35.468782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-09-26T21:49:35.468985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-26T21:49:35.469101Z","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-09-26T21:49:35.469226Z","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-09-26T21:49:35.470902Z","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-09-26T21:49:35.471041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-26T21:49:35.471064Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-26T21:49:35.471114Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-26T21:49:35.471221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-26T21:49:35.956592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-09-26T21:49:35.956669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-26T21:49:35.956696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-09-26T21:49:35.956925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-09-26T21:49:35.956971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-26T21:49:35.95699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-09-26T21:49:35.957004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-26T21:49:35.958393Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-26T21:49:35.958461Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-09-26T21:49:35.958657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-26T21:49:35.9587Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-26T21:49:35.959579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-09-26T21:49:35.959701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-26T21:49:35.959753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-26T21:49:35.963978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-26T21:49:35.964239Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-26T21:49:35.965281Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-26T21:49:35.965884Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-09-26T21:50:08.299318Z","caller":"traceutil/trace.go:171","msg":"trace[678205088] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"182.025945ms","start":"2026-09-26T21:50:08.117264Z","end":"2026-09-26T21:50:08.29929Z","steps":["trace[678205088] 'read index received' (duration: 181.675737ms)","trace[678205088] 'applied index is now lower than readState.Index' (duration: 349.508µs)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:50:08.29973Z","caller":"traceutil/trace.go:171","msg":"trace[1062698381] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"183.53268ms","start":"2026-09-26T21:50:08.116184Z","end":"2026-09-26T21:50:08.299716Z","steps":["trace[1062698381] 'process raft request' (duration: 182.966007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:08.29999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.69866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:50:08.300039Z","caller":"traceutil/trace.go:171","msg":"trace[554354331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"182.787403ms","start":"2026-09-26T21:50:08.117239Z","end":"2026-09-26T21:50:08.300026Z","steps":["trace[554354331] 'agreement among raft nodes before linearized reading' (duration: 182.697161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:08.300233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.712962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-26T21:50:08.30026Z","caller":"traceutil/trace.go:171","msg":"trace[1443053522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"123.760964ms","start":"2026-09-26T21:50:08.17649Z","end":"2026-09-26T21:50:08.300251Z","steps":["trace[1443053522] 'agreement among raft nodes before linearized reading' (duration: 123.702912ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:10.71163Z","caller":"traceutil/trace.go:171","msg":"trace[113455277] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"120.16443ms","start":"2026-09-26T21:50:10.591444Z","end":"2026-09-26T21:50:10.711609Z","steps":["trace[113455277] 'process raft request' (duration: 119.989936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.344474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.17179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:50:19.34473Z","caller":"traceutil/trace.go:171","msg":"trace[2079005040] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"581.36423ms","start":"2026-09-26T21:50:18.763287Z","end":"2026-09-26T21:50:19.344651Z","steps":["trace[2079005040] 'process raft request' (duration: 86.080905ms)","trace[2079005040] 'compare' (duration: 493.911114ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:50:19.344902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.763266Z","time spent":"581.599116ms","remote":"127.0.0.1:40076","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-09-26T21:50:19.345575Z","caller":"traceutil/trace.go:171","msg":"trace[292652204] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"431.612037ms","start":"2026-09-26T21:50:18.91394Z","end":"2026-09-26T21:50:19.345552Z","steps":["trace[292652204] 'process raft request' (duration: 431.513415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.345738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.913918Z","time spent":"431.723ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.356171Z","caller":"traceutil/trace.go:171","msg":"trace[1170249638] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"432.954419ms","start":"2026-09-26T21:50:18.923189Z","end":"2026-09-26T21:50:19.356143Z","steps":["trace[1170249638] 'process raft request' (duration: 432.740204ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:19.356209Z","caller":"traceutil/trace.go:171","msg":"trace[915397472] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"424.869303ms","start":"2026-09-26T21:50:18.931295Z","end":"2026-09-26T21:50:19.356164Z","steps":["trace[915397472] 'process raft request' (duration: 424.7315ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:19.356392Z","caller":"traceutil/trace.go:171","msg":"trace[410503505] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"417.368169ms","start":"2026-09-26T21:50:18.939015Z","end":"2026-09-26T21:50:19.356383Z","steps":["trace[410503505] 'process raft request' (duration: 417.206846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.356436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.939008Z","time spent":"417.40797ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.356535Z","caller":"traceutil/trace.go:171","msg":"trace[1095150185] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"417.600404ms","start":"2026-09-26T21:50:18.938925Z","end":"2026-09-26T21:50:19.356525Z","steps":["trace[1095150185] 'process raft request' (duration: 417.161575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.356511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.931265Z","time spent":"425.085897ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.356709Z","caller":"traceutil/trace.go:171","msg":"trace[1052824172] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"410.93182ms","start":"2026-09-26T21:50:18.945727Z","end":"2026-09-26T21:50:19.356658Z","steps":["trace[1052824172] 'process raft request' (duration: 410.544012ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:19.356839Z","caller":"traceutil/trace.go:171","msg":"trace[89407540] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"386.485747ms","start":"2026-09-26T21:50:18.970346Z","end":"2026-09-26T21:50:19.356832Z","steps":["trace[89407540] 'process raft request' (duration: 386.119129ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:19.356819Z","caller":"traceutil/trace.go:171","msg":"trace[528289485] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"406.513929ms","start":"2026-09-26T21:50:18.950275Z","end":"2026-09-26T21:50:19.356789Z","steps":["trace[528289485] 'process raft request' (duration: 406.0891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.356879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.970321Z","time spent":"386.541369ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-26T21:50:19.356888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.824817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:50:19.356929Z","caller":"traceutil/trace.go:171","msg":"trace[1541161428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"239.934379ms","start":"2026-09-26T21:50:19.116985Z","end":"2026-09-26T21:50:19.356919Z","steps":["trace[1541161428] 'agreement among raft nodes before linearized reading' (duration: 239.803026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.356575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.938906Z","time spent":"417.654416ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-26T21:50:19.356306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.923167Z","time spent":"433.075371ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.356729Z","caller":"traceutil/trace.go:171","msg":"trace[457815403] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:519; }","duration":"239.630533ms","start":"2026-09-26T21:50:19.117045Z","end":"2026-09-26T21:50:19.356676Z","steps":["trace[457815403] 'read index received' (duration: 228.505586ms)","trace[457815403] 'applied index is now lower than readState.Index' (duration: 11.123437ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:50:19.356783Z","caller":"traceutil/trace.go:171","msg":"trace[1793998286] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"410.590613ms","start":"2026-09-26T21:50:18.946186Z","end":"2026-09-26T21:50:19.356776Z","steps":["trace[1793998286] 'process raft request' (duration: 410.128683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.357097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.946133Z","time spent":"410.949932ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-26T21:50:19.357078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.950264Z","time spent":"406.630682ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-26T21:50:19.357234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.945705Z","time spent":"411.145316ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.356757Z","caller":"traceutil/trace.go:171","msg":"trace[1971798649] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"405.011094ms","start":"2026-09-26T21:50:18.951733Z","end":"2026-09-26T21:50:19.356744Z","steps":["trace[1971798649] 'process raft request' (duration: 404.683097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.357486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.951723Z","time spent":"405.708102ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-26T21:50:19.357137Z","caller":"traceutil/trace.go:171","msg":"trace[322714422] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"219.734625ms","start":"2026-09-26T21:50:19.137388Z","end":"2026-09-26T21:50:19.357122Z","steps":["trace[322714422] 'process raft request' (duration: 219.232192ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:19.356816Z","caller":"traceutil/trace.go:171","msg":"trace[1725524110] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"364.532672ms","start":"2026-09-26T21:50:18.992276Z","end":"2026-09-26T21:50:19.356809Z","steps":["trace[1725524110] 'process raft request' (duration: 364.250455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:19.357607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:18.99226Z","time spent":"365.32634ms","remote":"127.0.0.1:39864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-26T21:50:19.36651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.086116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-26T21:50:19.36657Z","caller":"traceutil/trace.go:171","msg":"trace[277724847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"139.208538ms","start":"2026-09-26T21:50:19.227347Z","end":"2026-09-26T21:50:19.366555Z","steps":["trace[277724847] 'agreement among raft nodes before linearized reading' (duration: 139.095806ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:23.560624Z","caller":"traceutil/trace.go:171","msg":"trace[1156465805] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"146.276542ms","start":"2026-09-26T21:50:23.414324Z","end":"2026-09-26T21:50:23.560601Z","steps":["trace[1156465805] 'process raft request' (duration: 142.226949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:25.613342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.055331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:50:25.613466Z","caller":"traceutil/trace.go:171","msg":"trace[1061390868] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"205.968407ms","start":"2026-09-26T21:50:25.407464Z","end":"2026-09-26T21:50:25.613433Z","steps":["trace[1061390868] 'read index received' (duration: 6.612449ms)","trace[1061390868] 'applied index is now lower than readState.Index' (duration: 199.354478ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:50:25.613531Z","caller":"traceutil/trace.go:171","msg":"trace[1025052770] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"256.722752ms","start":"2026-09-26T21:50:25.35675Z","end":"2026-09-26T21:50:25.613472Z","steps":["trace[1025052770] 'process raft request' (duration: 57.430006ms)","trace[1025052770] 'compare' (duration: 198.911397ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:50:25.613898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.400616ms","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-09-26T21:50:25.613989Z","caller":"traceutil/trace.go:171","msg":"trace[1549952656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"206.56668ms","start":"2026-09-26T21:50:25.407398Z","end":"2026-09-26T21:50:25.613964Z","steps":["trace[1549952656] 'agreement among raft nodes before linearized reading' (duration: 206.197321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:26.594176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.998395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:50:26.594429Z","caller":"traceutil/trace.go:171","msg":"trace[1150705356] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"973.08714ms","start":"2026-09-26T21:50:25.62132Z","end":"2026-09-26T21:50:26.594407Z","steps":["trace[1150705356] 'process raft request' (duration: 161.749166ms)","trace[1150705356] 'compare' (duration: 810.851442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:50:26.594492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:25.621288Z","time spent":"973.174283ms","remote":"127.0.0.1:40076","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-09-26T21:50:26.595179Z","caller":"traceutil/trace.go:171","msg":"trace[548797844] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"968.902161ms","start":"2026-09-26T21:50:25.626243Z","end":"2026-09-26T21:50:26.595145Z","steps":["trace[548797844] 'read index received' (duration: 39.753643ms)","trace[548797844] 'applied index is now lower than readState.Index' (duration: 929.146548ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:50:26.595394Z","caller":"traceutil/trace.go:171","msg":"trace[512111458] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"960.634642ms","start":"2026-09-26T21:50:25.634744Z","end":"2026-09-26T21:50:26.595378Z","steps":["trace[512111458] 'process raft request' (duration: 959.533995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:26.595526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:25.63465Z","time spent":"960.785105ms","remote":"127.0.0.1:40008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:50:26.595545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.289769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:50:26.5956Z","caller":"traceutil/trace.go:171","msg":"trace[1882532491] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:605; }","duration":"969.371791ms","start":"2026-09-26T21:50:25.62621Z","end":"2026-09-26T21:50:26.595582Z","steps":["trace[1882532491] 'agreement among raft nodes before linearized reading' (duration: 969.284309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:26.595635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:25.626196Z","time spent":"969.431743ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-09-26T21:50:26.595962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.309956ms","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-09-26T21:50:26.596153Z","caller":"traceutil/trace.go:171","msg":"trace[1704088650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"136.83919ms","start":"2026-09-26T21:50:26.459283Z","end":"2026-09-26T21:50:26.596122Z","steps":["trace[1704088650] 'agreement among raft nodes before linearized reading' (duration: 136.087052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:27.616421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977339287954466,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-26T21:50:28.117118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977339287954466,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-26T21:50:28.140728Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077707288s","expected-duration":"1s"} {"level":"info","ts":"2026-09-26T21:50:28.142737Z","caller":"traceutil/trace.go:171","msg":"trace[129062607] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"1.026326976s","start":"2026-09-26T21:50:27.11633Z","end":"2026-09-26T21:50:28.142657Z","steps":["trace[129062607] 'read index received' (duration: 1.024543253s)","trace[129062607] 'applied index is now lower than readState.Index' (duration: 1.782713ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:50:28.142915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026570752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:50:28.142904Z","caller":"traceutil/trace.go:171","msg":"trace[1310175231] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.081732143s","start":"2026-09-26T21:50:27.061118Z","end":"2026-09-26T21:50:28.14285Z","steps":["trace[1310175231] 'process raft request' (duration: 1.080918935s)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:28.143022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.388381ms","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-09-26T21:50:28.143061Z","caller":"traceutil/trace.go:171","msg":"trace[548114401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"472.478164ms","start":"2026-09-26T21:50:27.670574Z","end":"2026-09-26T21:50:28.143052Z","steps":["trace[548114401] 'agreement among raft nodes before linearized reading' (duration: 472.408933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:28.143101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:27.67055Z","time spent":"472.541995ms","remote":"127.0.0.1:40076","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-09-26T21:50:28.143168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:27.061011Z","time spent":"1.08200075s","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-26T21:50:28.142955Z","caller":"traceutil/trace.go:171","msg":"trace[638039349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"1.026691736s","start":"2026-09-26T21:50:27.116248Z","end":"2026-09-26T21:50:28.14294Z","steps":["trace[638039349] 'agreement among raft nodes before linearized reading' (duration: 1.026599212s)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:28.143488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:27.116212Z","time spent":"1.027229648s","remote":"127.0.0.1:39824","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-09-26T21:50:29.997899Z","caller":"traceutil/trace.go:171","msg":"trace[405509478] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"123.007918ms","start":"2026-09-26T21:50:29.874867Z","end":"2026-09-26T21:50:29.997875Z","steps":["trace[405509478] 'process raft request' (duration: 122.866955ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:50:44.652082Z","caller":"traceutil/trace.go:171","msg":"trace[1350193267] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"400.96363ms","start":"2026-09-26T21:50:44.251087Z","end":"2026-09-26T21:50:44.65205Z","steps":["trace[1350193267] 'process raft request' (duration: 400.794075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:50:44.652267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:50:44.251055Z","time spent":"401.111694ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-26T21:50:44.807209Z","caller":"traceutil/trace.go:171","msg":"trace[1799405487] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"111.585133ms","start":"2026-09-26T21:50:44.695594Z","end":"2026-09-26T21:50:44.807179Z","steps":["trace[1799405487] 'read index received' (duration: 106.902631ms)","trace[1799405487] 'applied index is now lower than readState.Index' (duration: 4.680382ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:50:44.807376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.780578ms","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-09-26T21:50:44.807493Z","caller":"traceutil/trace.go:171","msg":"trace[713088219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"111.956753ms","start":"2026-09-26T21:50:44.695522Z","end":"2026-09-26T21:50:44.807478Z","steps":["trace[713088219] 'agreement among raft nodes before linearized reading' (duration: 111.738887ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:27.543899Z","caller":"traceutil/trace.go:171","msg":"trace[233020641] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"668.793719ms","start":"2026-09-26T21:51:26.875069Z","end":"2026-09-26T21:51:27.543863Z","steps":["trace[233020641] 'process raft request' (duration: 668.504821ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:27.544009Z","caller":"traceutil/trace.go:171","msg":"trace[244140532] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"488.114628ms","start":"2026-09-26T21:51:27.055859Z","end":"2026-09-26T21:51:27.543974Z","steps":["trace[244140532] 'read index received' (duration: 487.615976ms)","trace[244140532] 'applied index is now lower than readState.Index' (duration: 493.362µs)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:51:27.544073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:26.875046Z","time spent":"668.941602ms","remote":"127.0.0.1:40076","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-09-26T21:51:27.54427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.970968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-26T21:51:27.54426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.757182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-26T21:51:27.544274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.390875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-26T21:51:27.544336Z","caller":"traceutil/trace.go:171","msg":"trace[1075913352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"427.861234ms","start":"2026-09-26T21:51:27.116455Z","end":"2026-09-26T21:51:27.544316Z","steps":["trace[1075913352] 'agreement among raft nodes before linearized reading' (duration: 427.731971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:27.544347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.79686ms","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-09-26T21:51:27.544368Z","caller":"traceutil/trace.go:171","msg":"trace[1793592284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"477.836731ms","start":"2026-09-26T21:51:27.066525Z","end":"2026-09-26T21:51:27.544362Z","steps":["trace[1793592284] 'agreement among raft nodes before linearized reading' (duration: 477.78127ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:27.544355Z","caller":"traceutil/trace.go:171","msg":"trace[912333001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"488.510088ms","start":"2026-09-26T21:51:27.055824Z","end":"2026-09-26T21:51:27.544334Z","steps":["trace[912333001] 'agreement among raft nodes before linearized reading' (duration: 488.245172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:27.544377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.116443Z","time spent":"427.922637ms","remote":"127.0.0.1:39824","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-09-26T21:51:27.544394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.066514Z","time spent":"477.875342ms","remote":"127.0.0.1:40076","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-09-26T21:51:27.544402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.055805Z","time spent":"488.588419ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-26T21:51:27.544296Z","caller":"traceutil/trace.go:171","msg":"trace[600148697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"481.08034ms","start":"2026-09-26T21:51:27.063207Z","end":"2026-09-26T21:51:27.544287Z","steps":["trace[600148697] 'agreement among raft nodes before linearized reading' (duration: 480.927976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:27.544448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.063186Z","time spent":"481.251004ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-26T21:51:28.114152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977339287955490,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-26T21:51:28.292886Z","caller":"traceutil/trace.go:171","msg":"trace[1072258344] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1112; }","duration":"681.616615ms","start":"2026-09-26T21:51:27.611248Z","end":"2026-09-26T21:51:28.292864Z","steps":["trace[1072258344] 'read index received' (duration: 680.324425ms)","trace[1072258344] 'applied index is now lower than readState.Index' (duration: 1.29135ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:51:28.29302Z","caller":"traceutil/trace.go:171","msg":"trace[1712782182] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"243.642789ms","start":"2026-09-26T21:51:28.049367Z","end":"2026-09-26T21:51:28.29301Z","steps":["trace[1712782182] 'process raft request' (duration: 243.466374ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:28.293032Z","caller":"traceutil/trace.go:171","msg":"trace[589557923] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"742.465404ms","start":"2026-09-26T21:51:27.550536Z","end":"2026-09-26T21:51:28.293002Z","steps":["trace[589557923] 'process raft request' (duration: 742.258248ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:28.293133Z","caller":"traceutil/trace.go:171","msg":"trace[1267516035] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"742.883694ms","start":"2026-09-26T21:51:27.55021Z","end":"2026-09-26T21:51:28.293094Z","steps":["trace[1267516035] 'process raft request' (duration: 741.430659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:28.29319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.55052Z","time spent":"742.585606ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-26T21:51:28.293229Z","caller":"traceutil/trace.go:171","msg":"trace[2050879189] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"742.982176ms","start":"2026-09-26T21:51:27.550218Z","end":"2026-09-26T21:51:28.2932Z","steps":["trace[2050879189] 'process raft request' (duration: 742.471393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:28.293311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.550188Z","time spent":"743.028027ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:51:28.293321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.743238ms","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-09-26T21:51:28.293379Z","caller":"traceutil/trace.go:171","msg":"trace[124977575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"479.84801ms","start":"2026-09-26T21:51:27.813515Z","end":"2026-09-26T21:51:28.293363Z","steps":["trace[124977575] 'agreement among raft nodes before linearized reading' (duration: 479.668136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:28.293371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.550196Z","time spent":"743.107929ms","remote":"127.0.0.1:40076","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-09-26T21:51:28.293417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.813501Z","time spent":"479.908262ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-26T21:51:28.293496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.22974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:51:28.293547Z","caller":"traceutil/trace.go:171","msg":"trace[1432798881] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1074; }","duration":"682.317351ms","start":"2026-09-26T21:51:27.611218Z","end":"2026-09-26T21:51:28.293536Z","steps":["trace[1432798881] 'agreement among raft nodes before linearized reading' (duration: 682.230059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:28.293584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:27.611206Z","time spent":"682.368923ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-09-26T21:51:48.821229Z","caller":"traceutil/trace.go:171","msg":"trace[1325224691] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"314.533318ms","start":"2026-09-26T21:51:48.506666Z","end":"2026-09-26T21:51:48.821199Z","steps":["trace[1325224691] 'read index received' (duration: 314.291363ms)","trace[1325224691] 'applied index is now lower than readState.Index' (duration: 240.785µs)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:51:48.821319Z","caller":"traceutil/trace.go:171","msg":"trace[1747417054] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"341.109664ms","start":"2026-09-26T21:51:48.48018Z","end":"2026-09-26T21:51:48.821289Z","steps":["trace[1747417054] 'process raft request' (duration: 340.811957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:48.821491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.480161Z","time spent":"341.230647ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:51:48.821557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.863456ms","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-09-26T21:51:48.821601Z","caller":"traceutil/trace.go:171","msg":"trace[484866083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1164; }","duration":"314.976658ms","start":"2026-09-26T21:51:48.506612Z","end":"2026-09-26T21:51:48.821589Z","steps":["trace[484866083] 'agreement among raft nodes before linearized reading' (duration: 314.736303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:48.821638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.506588Z","time spent":"315.04189ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-26T21:51:48.82165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.742219ms","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-09-26T21:51:48.821772Z","caller":"traceutil/trace.go:171","msg":"trace[1310450201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"313.901772ms","start":"2026-09-26T21:51:48.507816Z","end":"2026-09-26T21:51:48.821718Z","steps":["trace[1310450201] 'agreement among raft nodes before linearized reading' (duration: 313.614876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:48.821842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.507792Z","time spent":"314.031867ms","remote":"127.0.0.1:40076","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-09-26T21:51:48.821865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.587332ms","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-09-26T21:51:48.821985Z","caller":"traceutil/trace.go:171","msg":"trace[280623774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1164; }","duration":"312.753666ms","start":"2026-09-26T21:51:48.509193Z","end":"2026-09-26T21:51:48.821946Z","steps":["trace[280623774] 'agreement among raft nodes before linearized reading' (duration: 312.371716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:48.822043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.509182Z","time spent":"312.851149ms","remote":"127.0.0.1:40076","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-09-26T21:51:48.822586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.262168ms","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-09-26T21:51:48.822629Z","caller":"traceutil/trace.go:171","msg":"trace[1890492041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1164; }","duration":"313.327219ms","start":"2026-09-26T21:51:48.509291Z","end":"2026-09-26T21:51:48.822619Z","steps":["trace[1890492041] 'agreement among raft nodes before linearized reading' (duration: 313.191336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:48.822657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.509276Z","time spent":"313.37385ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-26T21:51:49.225095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.991765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:51:49.225339Z","caller":"traceutil/trace.go:171","msg":"trace[828071326] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"397.976119ms","start":"2026-09-26T21:51:48.827322Z","end":"2026-09-26T21:51:49.225298Z","steps":["trace[828071326] 'process raft request' (duration: 239.654635ms)","trace[828071326] 'compare' (duration: 157.531165ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:51:49.225391Z","caller":"traceutil/trace.go:171","msg":"trace[2034436322] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1211; }","duration":"107.406414ms","start":"2026-09-26T21:51:49.11797Z","end":"2026-09-26T21:51:49.225377Z","steps":["trace[2034436322] 'read index received' (duration: 40.354827ms)","trace[2034436322] 'applied index is now lower than readState.Index' (duration: 67.050537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:51:49.225428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.827292Z","time spent":"398.094911ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:51:49.225471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.509947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:51:49.225495Z","caller":"traceutil/trace.go:171","msg":"trace[1875068234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"107.595478ms","start":"2026-09-26T21:51:49.11789Z","end":"2026-09-26T21:51:49.225485Z","steps":["trace[1875068234] 'agreement among raft nodes before linearized reading' (duration: 107.530227ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:49.225711Z","caller":"traceutil/trace.go:171","msg":"trace[421577715] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"397.088127ms","start":"2026-09-26T21:51:48.828612Z","end":"2026-09-26T21:51:49.2257Z","steps":["trace[421577715] 'process raft request' (duration: 396.618546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:49.225791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.82858Z","time spent":"397.179079ms","remote":"127.0.0.1:40076","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-09-26T21:51:49.225867Z","caller":"traceutil/trace.go:171","msg":"trace[1176586015] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"397.016184ms","start":"2026-09-26T21:51:48.828842Z","end":"2026-09-26T21:51:49.225858Z","steps":["trace[1176586015] 'process raft request' (duration: 396.459001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:49.225916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.828832Z","time spent":"397.055195ms","remote":"127.0.0.1:40076","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-09-26T21:51:49.225987Z","caller":"traceutil/trace.go:171","msg":"trace[1529046005] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"396.961733ms","start":"2026-09-26T21:51:48.829017Z","end":"2026-09-26T21:51:49.225979Z","steps":["trace[1529046005] 'process raft request' (duration: 396.327018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:49.226024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:48.828977Z","time spent":"397.025644ms","remote":"127.0.0.1:40076","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-09-26T21:51:51.168147Z","caller":"traceutil/trace.go:171","msg":"trace[684252437] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"340.806467ms","start":"2026-09-26T21:51:50.827317Z","end":"2026-09-26T21:51:51.168123Z","steps":["trace[684252437] 'process raft request' (duration: 340.572351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.168287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:50.827292Z","time spent":"340.92119ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:51:51.343119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.378052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:51:51.343226Z","caller":"traceutil/trace.go:171","msg":"trace[739062400] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"227.01782ms","start":"2026-09-26T21:51:51.116189Z","end":"2026-09-26T21:51:51.343207Z","steps":["trace[739062400] 'read index received' (duration: 51.791481ms)","trace[739062400] 'applied index is now lower than readState.Index' (duration: 175.224989ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:51:51.343325Z","caller":"traceutil/trace.go:171","msg":"trace[1565662335] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"354.922735ms","start":"2026-09-26T21:51:50.98839Z","end":"2026-09-26T21:51:51.343313Z","steps":["trace[1565662335] 'process raft request' (duration: 247.259476ms)","trace[1565662335] 'compare' (duration: 107.218199ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:51:51.343389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:50.98836Z","time spent":"354.997817ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:51:51.34353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.741713ms","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-09-26T21:51:51.343627Z","caller":"traceutil/trace.go:171","msg":"trace[222072226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"112.953877ms","start":"2026-09-26T21:51:51.23065Z","end":"2026-09-26T21:51:51.343604Z","steps":["trace[222072226] 'agreement among raft nodes before linearized reading' (duration: 112.738041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.343824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.196889ms","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-09-26T21:51:51.343851Z","caller":"traceutil/trace.go:171","msg":"trace[723835584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1172; }","duration":"112.23862ms","start":"2026-09-26T21:51:51.231602Z","end":"2026-09-26T21:51:51.343841Z","steps":["trace[723835584] 'agreement among raft nodes before linearized reading' (duration: 112.162258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.343914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.25025ms","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-09-26T21:51:51.343949Z","caller":"traceutil/trace.go:171","msg":"trace[1577071850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"112.298701ms","start":"2026-09-26T21:51:51.231639Z","end":"2026-09-26T21:51:51.343938Z","steps":["trace[1577071850] 'agreement among raft nodes before linearized reading' (duration: 112.119707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.343966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.872105ms","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-09-26T21:51:51.344007Z","caller":"traceutil/trace.go:171","msg":"trace[1961600015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1172; }","duration":"112.932457ms","start":"2026-09-26T21:51:51.231067Z","end":"2026-09-26T21:51:51.343999Z","steps":["trace[1961600015] 'agreement among raft nodes before linearized reading' (duration: 112.844155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.344108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.462258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:51:51.344129Z","caller":"traceutil/trace.go:171","msg":"trace[1477605503] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1172; }","duration":"110.504648ms","start":"2026-09-26T21:51:51.233617Z","end":"2026-09-26T21:51:51.344122Z","steps":["trace[1477605503] 'agreement among raft nodes before linearized reading' (duration: 110.462977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:51.344223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.000344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:51:51.344333Z","caller":"traceutil/trace.go:171","msg":"trace[1302065682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"228.161998ms","start":"2026-09-26T21:51:51.116148Z","end":"2026-09-26T21:51:51.34431Z","steps":["trace[1302065682] 'agreement among raft nodes before linearized reading' (duration: 227.979903ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:51.57871Z","caller":"traceutil/trace.go:171","msg":"trace[819929474] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"228.69363ms","start":"2026-09-26T21:51:51.349958Z","end":"2026-09-26T21:51:51.578652Z","steps":["trace[819929474] 'process raft request' (duration: 177.278339ms)","trace[819929474] 'compare' (duration: 51.058243ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:51:51.579145Z","caller":"traceutil/trace.go:171","msg":"trace[628174441] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"226.229582ms","start":"2026-09-26T21:51:51.352888Z","end":"2026-09-26T21:51:51.579118Z","steps":["trace[628174441] 'process raft request' (duration: 226.12608ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:51.57913Z","caller":"traceutil/trace.go:171","msg":"trace[1385573565] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"226.335294ms","start":"2026-09-26T21:51:51.352772Z","end":"2026-09-26T21:51:51.579107Z","steps":["trace[1385573565] 'process raft request' (duration: 225.79112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:51:52.285041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.313185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:51:52.285165Z","caller":"traceutil/trace.go:171","msg":"trace[1822801403] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"167.885623ms","start":"2026-09-26T21:51:52.117261Z","end":"2026-09-26T21:51:52.285147Z","steps":["trace[1822801403] 'read index received' (duration: 41.941µs)","trace[1822801403] 'applied index is now lower than readState.Index' (duration: 167.842412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:51:52.285242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.986166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:51:52.285266Z","caller":"traceutil/trace.go:171","msg":"trace[310202302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"168.049698ms","start":"2026-09-26T21:51:52.117207Z","end":"2026-09-26T21:51:52.285257Z","steps":["trace[310202302] 'agreement among raft nodes before linearized reading' (duration: 167.990516ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:52.285384Z","caller":"traceutil/trace.go:171","msg":"trace[1685012272] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"604.176499ms","start":"2026-09-26T21:51:51.681173Z","end":"2026-09-26T21:51:52.28535Z","steps":["trace[1685012272] 'process raft request' (duration: 316.461284ms)","trace[1685012272] 'compare' (duration: 286.710011ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:51:52.28556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:51:51.681145Z","time spent":"604.307171ms","remote":"127.0.0.1:40076","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-09-26T21:51:52.49867Z","caller":"traceutil/trace.go:171","msg":"trace[136671802] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"193.085747ms","start":"2026-09-26T21:51:52.30556Z","end":"2026-09-26T21:51:52.498646Z","steps":["trace[136671802] 'process raft request' (duration: 193.035906ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:51:52.4991Z","caller":"traceutil/trace.go:171","msg":"trace[682214535] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"207.557305ms","start":"2026-09-26T21:51:52.291529Z","end":"2026-09-26T21:51:52.499087Z","steps":["trace[682214535] 'process raft request' (duration: 177.72762ms)","trace[682214535] 'compare' (duration: 29.016765ms)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:52:01.257362Z","caller":"traceutil/trace.go:171","msg":"trace[1731323727] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"140.162449ms","start":"2026-09-26T21:52:01.117148Z","end":"2026-09-26T21:52:01.25731Z","steps":["trace[1731323727] 'read index received' (duration: 139.845111ms)","trace[1731323727] 'applied index is now lower than readState.Index' (duration: 315.288µs)"],"step_count":2} {"level":"info","ts":"2026-09-26T21:52:01.257494Z","caller":"traceutil/trace.go:171","msg":"trace[736733885] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"214.952882ms","start":"2026-09-26T21:52:01.042516Z","end":"2026-09-26T21:52:01.257469Z","steps":["trace[736733885] 'process raft request' (duration: 214.523282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.257583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.415265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:52:01.257654Z","caller":"traceutil/trace.go:171","msg":"trace[712399175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"140.554339ms","start":"2026-09-26T21:52:01.117088Z","end":"2026-09-26T21:52:01.257642Z","steps":["trace[712399175] 'agreement among raft nodes before linearized reading' (duration: 140.393705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.79669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.706196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-26T21:52:01.796903Z","caller":"traceutil/trace.go:171","msg":"trace[1604462731] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"436.764106ms","start":"2026-09-26T21:52:01.36012Z","end":"2026-09-26T21:52:01.796884Z","steps":["trace[1604462731] 'process raft request' (duration: 436.697635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.797003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.360079Z","time spent":"436.88995ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-26T21:52:01.797224Z","caller":"traceutil/trace.go:171","msg":"trace[1495718313] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"545.707838ms","start":"2026-09-26T21:52:01.251504Z","end":"2026-09-26T21:52:01.797212Z","steps":["trace[1495718313] 'process raft request' (duration: 214.357487ms)","trace[1495718313] 'compare' (duration: 330.408728ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:52:01.797288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.251483Z","time spent":"545.77157ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-26T21:52:01.79737Z","caller":"traceutil/trace.go:171","msg":"trace[233870953] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"538.225909ms","start":"2026-09-26T21:52:01.259089Z","end":"2026-09-26T21:52:01.797315Z","steps":["trace[233870953] 'read index received' (duration: 206.668573ms)","trace[233870953] 'applied index is now lower than readState.Index' (duration: 331.549486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:52:01.797848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.896597ms","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-09-26T21:52:01.797898Z","caller":"traceutil/trace.go:171","msg":"trace[110554363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"174.001ms","start":"2026-09-26T21:52:01.623884Z","end":"2026-09-26T21:52:01.797885Z","steps":["trace[110554363] 'agreement among raft nodes before linearized reading' (duration: 173.832716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.798002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.342906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-26T21:52:01.798051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.676553ms","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-09-26T21:52:01.798097Z","caller":"traceutil/trace.go:171","msg":"trace[159602975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"176.5093ms","start":"2026-09-26T21:52:01.621564Z","end":"2026-09-26T21:52:01.798074Z","steps":["trace[159602975] 'agreement among raft nodes before linearized reading' (duration: 176.161092ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:52:01.798155Z","caller":"traceutil/trace.go:171","msg":"trace[1660227162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"171.820797ms","start":"2026-09-26T21:52:01.626309Z","end":"2026-09-26T21:52:01.79813Z","steps":["trace[1660227162] 'agreement among raft nodes before linearized reading' (duration: 171.458679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.798021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.514174ms","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-09-26T21:52:01.798269Z","caller":"traceutil/trace.go:171","msg":"trace[716367574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"346.875932ms","start":"2026-09-26T21:52:01.451382Z","end":"2026-09-26T21:52:01.798258Z","steps":["trace[716367574] 'agreement among raft nodes before linearized reading' (duration: 346.326189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.798312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.45136Z","time spent":"346.938834ms","remote":"127.0.0.1:40076","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-09-26T21:52:01.798764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.919895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-26T21:52:01.798894Z","caller":"traceutil/trace.go:171","msg":"trace[841986893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"539.787176ms","start":"2026-09-26T21:52:01.25906Z","end":"2026-09-26T21:52:01.798847Z","steps":["trace[841986893] 'agreement among raft nodes before linearized reading' (duration: 538.569467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:01.79896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.259049Z","time spent":"539.887629ms","remote":"127.0.0.1:39820","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-09-26T21:52:02.423703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977339287955858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-26T21:52:02.526788Z","caller":"traceutil/trace.go:171","msg":"trace[116649220] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"722.125546ms","start":"2026-09-26T21:52:01.804632Z","end":"2026-09-26T21:52:02.526758Z","steps":["trace[116649220] 'process raft request' (duration: 721.823649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:02.526952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.804605Z","time spent":"722.248169ms","remote":"127.0.0.1:40076","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-09-26T21:52:02.527112Z","caller":"traceutil/trace.go:171","msg":"trace[1160806161] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"721.426779ms","start":"2026-09-26T21:52:01.80567Z","end":"2026-09-26T21:52:02.527097Z","steps":["trace[1160806161] 'process raft request' (duration: 720.983259ms)"],"step_count":1} {"level":"info","ts":"2026-09-26T21:52:02.527126Z","caller":"traceutil/trace.go:171","msg":"trace[1450524309] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1296; }","duration":"603.80729ms","start":"2026-09-26T21:52:01.923292Z","end":"2026-09-26T21:52:02.527099Z","steps":["trace[1450524309] 'read index received' (duration: 603.078063ms)","trace[1450524309] 'applied index is now lower than readState.Index' (duration: 727.807µs)"],"step_count":2} {"level":"warn","ts":"2026-09-26T21:52:02.527174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.805648Z","time spent":"721.490971ms","remote":"127.0.0.1:40076","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-09-26T21:52:02.527293Z","caller":"traceutil/trace.go:171","msg":"trace[1144166530] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"720.267651ms","start":"2026-09-26T21:52:01.806887Z","end":"2026-09-26T21:52:02.527155Z","steps":["trace[1144166530] 'process raft request' (duration: 720.075257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:02.527348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.035716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-26T21:52:02.527389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.849092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-26T21:52:02.52744Z","caller":"traceutil/trace.go:171","msg":"trace[1399093741] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1249; }","duration":"346.937565ms","start":"2026-09-26T21:52:02.180492Z","end":"2026-09-26T21:52:02.527429Z","steps":["trace[1399093741] 'agreement among raft nodes before linearized reading' (duration: 346.785341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:02.527483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:02.180463Z","time spent":"347.013727ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-09-26T21:52:02.527395Z","caller":"traceutil/trace.go:171","msg":"trace[23854952] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1249; }","duration":"604.156919ms","start":"2026-09-26T21:52:01.923223Z","end":"2026-09-26T21:52:02.52738Z","steps":["trace[23854952] 'agreement among raft nodes before linearized reading' (duration: 604.010875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-26T21:52:02.527698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.923204Z","time spent":"604.477056ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-09-26T21:52:02.527681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-26T21:52:01.806876Z","time spent":"720.508937ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-26T21:52:02.528211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.657954ms","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-09-26T21:52:02.528368Z","caller":"traceutil/trace.go:171","msg":"trace[226351996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"196.899839ms","start":"2026-09-26T21:52:02.331444Z","end":"2026-09-26T21:52:02.528344Z","steps":["trace[226351996] 'agreement among raft nodes before linearized reading' (duration: 196.152421ms)"],"step_count":1}