{"level":"warn","ts":"2026-02-10T14:59:03.670613Z","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-10T14:59:03.670704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-10T14:59:03.670768Z","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-10T14:59:03.670777Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-10T14:59:03.670798Z","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-10T14:59:03.67144Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-02-10T14:59:03.671509Z","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":2,"max-cpu-available":2,"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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-10T14:59:03.678199Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.443751ms"} {"level":"info","ts":"2026-02-10T14:59:03.687341Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-02-10T14:59:03.687394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T14:59:03.68741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-02-10T14:59:03.687419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T14:59:03.687425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-02-10T14:59:03.687453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-02-10T14:59:03.69309Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T14:59:03.718312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T14:59:03.723824Z","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-10T14:59:03.734074Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T14:59:03.743243Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T14:59:03.744364Z","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-10T14:59:03.745223Z","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-10T14:59:03.745253Z","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-10T14:59:03.745584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-02-10T14:59:03.745667Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-10T14:59:03.746289Z","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-10T14:59:03.746396Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-10T14:59:03.748776Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-10T14:59:03.748899Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T14:59:03.749761Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T14:59:04.489163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T14:59:04.489208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T14:59:04.489374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-02-10T14:59:04.489398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-02-10T14:59:04.489489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-10T14:59:04.48955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-02-10T14:59:04.48961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-10T14:59:04.492605Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T14:59:04.492631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:04.493179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:04.500077Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:04.500532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T14:59:04.504304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T14:59:04.504425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T14:59:04.504658Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:04.5048Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:04.504821Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:04.511533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"warn","ts":"2026-02-10T14:59:06.617477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:07.118427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:07.619191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:08.119977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:08.630926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:09.131208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:09.196406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000485188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-10T14:59:09.196512Z","caller":"traceutil/trace.go:171","msg":"trace[972836250] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000665843s","start":"2026-02-10T14:59:07.195823Z","end":"2026-02-10T14:59:09.196488Z","steps":["trace[972836250] 'agreement among raft nodes before linearized reading' (duration: 2.000512139s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:09.19658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:07.195794Z","time spent":"2.000773166s","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:09.631819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841752952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:09.789132Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.680209935s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T14:59:09.789456Z","caller":"traceutil/trace.go:171","msg":"trace[668069440] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"3.680569713s","start":"2026-02-10T14:59:06.10887Z","end":"2026-02-10T14:59:09.78944Z","steps":["trace[668069440] 'process raft request' (duration: 3.68043977s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:09.789922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.108861Z","time spent":"3.680629816s","remote":"127.0.0.1:38678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:10.593625Z","caller":"traceutil/trace.go:171","msg":"trace[1123459467] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"4.477161419s","start":"2026-02-10T14:59:06.11644Z","end":"2026-02-10T14:59:10.593601Z","steps":["trace[1123459467] 'read index received' (duration: 3.673758512s)","trace[1123459467] 'applied index is now lower than readState.Index' (duration: 803.402017ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:10.593747Z","caller":"traceutil/trace.go:171","msg":"trace[765679326] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"4.484535815s","start":"2026-02-10T14:59:06.109202Z","end":"2026-02-10T14:59:10.593738Z","steps":["trace[765679326] 'process raft request' (duration: 4.483991781s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.59385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.109181Z","time spent":"4.484594487s","remote":"127.0.0.1:38662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:10.594286Z","caller":"traceutil/trace.go:171","msg":"trace[1363701462] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"4.475998429s","start":"2026-02-10T14:59:06.118277Z","end":"2026-02-10T14:59:10.594275Z","steps":["trace[1363701462] 'process raft request' (duration: 4.475034483s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.594339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.118269Z","time spent":"4.47604519s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.594525Z","caller":"traceutil/trace.go:171","msg":"trace[221655237] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"4.429952467s","start":"2026-02-10T14:59:06.164563Z","end":"2026-02-10T14:59:10.594516Z","steps":["trace[221655237] 'process raft request' (duration: 4.428798126s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.594576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.164534Z","time spent":"4.430014829s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2847,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.595204Z","caller":"traceutil/trace.go:171","msg":"trace[460311519] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"4.428132718s","start":"2026-02-10T14:59:06.167059Z","end":"2026-02-10T14:59:10.595192Z","steps":["trace[460311519] 'process raft request' (duration: 4.4263379s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.59527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.167052Z","time spent":"4.42818013s","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.59545Z","caller":"traceutil/trace.go:171","msg":"trace[202623843] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"4.425863478s","start":"2026-02-10T14:59:06.169578Z","end":"2026-02-10T14:59:10.595441Z","steps":["trace[202623843] 'process raft request' (duration: 4.423870155s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.595503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.169566Z","time spent":"4.42591719s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.59563Z","caller":"traceutil/trace.go:171","msg":"trace[1611573825] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"4.418235445s","start":"2026-02-10T14:59:06.177381Z","end":"2026-02-10T14:59:10.595616Z","steps":["trace[1611573825] 'process raft request' (duration: 4.416090338s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.595696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.177372Z","time spent":"4.418293388s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.595825Z","caller":"traceutil/trace.go:171","msg":"trace[1920790528] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"4.411808135s","start":"2026-02-10T14:59:06.184006Z","end":"2026-02-10T14:59:10.595814Z","steps":["trace[1920790528] 'process raft request' (duration: 4.409499973s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.595878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.183992Z","time spent":"4.411861796s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.595999Z","caller":"traceutil/trace.go:171","msg":"trace[1299605147] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"4.411882327s","start":"2026-02-10T14:59:06.184109Z","end":"2026-02-10T14:59:10.595991Z","steps":["trace[1299605147] 'process raft request' (duration: 4.409416651s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.596037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.184102Z","time spent":"4.411917828s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:10.596177Z","caller":"traceutil/trace.go:171","msg":"trace[1047783087] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"4.41200486s","start":"2026-02-10T14:59:06.184165Z","end":"2026-02-10T14:59:10.59617Z","steps":["trace[1047783087] 'process raft request' (duration: 4.409404462s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.596223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.184158Z","time spent":"4.412039451s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:10.596382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.479933253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.596416Z","caller":"traceutil/trace.go:171","msg":"trace[1945931202] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:0; response_revision:11; }","duration":"4.479976494s","start":"2026-02-10T14:59:06.116428Z","end":"2026-02-10T14:59:10.596404Z","steps":["trace[1945931202] 'agreement among raft nodes before linearized reading' (duration: 4.479888461s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.596458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.116422Z","time spent":"4.480029396s","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.140\" "} {"level":"warn","ts":"2026-02-10T14:59:10.596623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.479215254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.596682Z","caller":"traceutil/trace.go:171","msg":"trace[943183518] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"4.479276715s","start":"2026-02-10T14:59:06.117389Z","end":"2026-02-10T14:59:10.596666Z","steps":["trace[943183518] 'agreement among raft nodes before linearized reading' (duration: 4.479203583s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.596711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.117385Z","time spent":"4.479319286s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:10.597231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.381087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.597282Z","caller":"traceutil/trace.go:171","msg":"trace[1887040143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"901.51251ms","start":"2026-02-10T14:59:09.695758Z","end":"2026-02-10T14:59:10.597271Z","steps":["trace[1887040143] 'agreement among raft nodes before linearized reading' (duration: 901.337336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.597318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:09.695734Z","time spent":"901.577841ms","remote":"127.0.0.1:38594","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-10T14:59:10.597486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.578669941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.597522Z","caller":"traceutil/trace.go:171","msg":"trace[937064779] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"3.578728322s","start":"2026-02-10T14:59:07.018785Z","end":"2026-02-10T14:59:10.597514Z","steps":["trace[937064779] 'agreement among raft nodes before linearized reading' (duration: 3.578677481s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.597563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:07.018776Z","time spent":"3.578772103s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-10T14:59:10.597879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.58052121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.597919Z","caller":"traceutil/trace.go:171","msg":"trace[1730471870] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"3.580611153s","start":"2026-02-10T14:59:07.017299Z","end":"2026-02-10T14:59:10.59791Z","steps":["trace[1730471870] 'agreement among raft nodes before linearized reading' (duration: 3.580550732s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.597952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:07.017281Z","time spent":"3.580664944s","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-10T14:59:10.598116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.287269421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.598158Z","caller":"traceutil/trace.go:171","msg":"trace[1281854024] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"4.287342233s","start":"2026-02-10T14:59:06.310806Z","end":"2026-02-10T14:59:10.598149Z","steps":["trace[1281854024] 'agreement among raft nodes before linearized reading' (duration: 4.287277312s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.598197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.310795Z","time spent":"4.287391224s","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-10T14:59:10.598459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.428682193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.598503Z","caller":"traceutil/trace.go:171","msg":"trace[1804637393] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"4.428749635s","start":"2026-02-10T14:59:06.16974Z","end":"2026-02-10T14:59:10.598489Z","steps":["trace[1804637393] 'agreement among raft nodes before linearized reading' (duration: 4.428694552s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.59853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.169695Z","time spent":"4.428828457s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:10.598641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.436248964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.598668Z","caller":"traceutil/trace.go:171","msg":"trace[2018860094] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"4.436297235s","start":"2026-02-10T14:59:06.162364Z","end":"2026-02-10T14:59:10.598661Z","steps":["trace[2018860094] 'agreement among raft nodes before linearized reading' (duration: 4.436261464s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.598691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.162353Z","time spent":"4.436333096s","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-10T14:59:10.598836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.480113307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:10.598861Z","caller":"traceutil/trace.go:171","msg":"trace[1347025451] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"4.480146438s","start":"2026-02-10T14:59:06.118708Z","end":"2026-02-10T14:59:10.598854Z","steps":["trace[1347025451] 'agreement among raft nodes before linearized reading' (duration: 4.480109617s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:10.598916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:06.118703Z","time spent":"4.480205659s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-10T14:59:11.269339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.064386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:11.269422Z","caller":"traceutil/trace.go:171","msg":"trace[74652001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"662.195029ms","start":"2026-02-10T14:59:10.607204Z","end":"2026-02-10T14:59:11.269399Z","steps":["trace[74652001] 'range keys from in-memory index tree' (duration: 661.989434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:11.269462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.607185Z","time spent":"662.269231ms","remote":"127.0.0.1:38608","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-10T14:59:11.270197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.139113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:11.270288Z","caller":"traceutil/trace.go:171","msg":"trace[67413317] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"651.525236ms","start":"2026-02-10T14:59:10.618751Z","end":"2026-02-10T14:59:11.270277Z","steps":["trace[67413317] 'read index received' (duration: 492.123207ms)","trace[67413317] 'applied index is now lower than readState.Index' (duration: 159.401079ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:11.270485Z","caller":"traceutil/trace.go:171","msg":"trace[1447811084] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"662.763694ms","start":"2026-02-10T14:59:10.607708Z","end":"2026-02-10T14:59:11.270472Z","steps":["trace[1447811084] 'process raft request' (duration: 503.15964ms)","trace[1447811084] 'compare' (duration: 158.013212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:11.270537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.607699Z","time spent":"662.813935ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:11.270759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.002989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2859"} {"level":"info","ts":"2026-02-10T14:59:11.27079Z","caller":"traceutil/trace.go:171","msg":"trace[1123898383] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"652.059541ms","start":"2026-02-10T14:59:10.618722Z","end":"2026-02-10T14:59:11.270781Z","steps":["trace[1123898383] 'agreement among raft nodes before linearized reading' (duration: 651.921497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:11.270818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.618708Z","time spent":"652.102993ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2882,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-10T14:59:12.100569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.447466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:12.101045Z","caller":"traceutil/trace.go:171","msg":"trace[1548400363] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:15; }","duration":"830.69492ms","start":"2026-02-10T14:59:11.270311Z","end":"2026-02-10T14:59:12.101006Z","steps":["trace[1548400363] 'read index received' (duration: 251.862023ms)","trace[1548400363] 'applied index is now lower than readState.Index' (duration: 578.832087ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:12.101297Z","caller":"traceutil/trace.go:171","msg":"trace[944614104] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"825.640716ms","start":"2026-02-10T14:59:11.275632Z","end":"2026-02-10T14:59:12.101272Z","steps":["trace[944614104] 'process raft request' (duration: 825.533133ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:12.101419Z","caller":"traceutil/trace.go:171","msg":"trace[1123403523] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.481849085s","start":"2026-02-10T14:59:10.619562Z","end":"2026-02-10T14:59:12.101411Z","steps":["trace[1123403523] 'process raft request' (duration: 1.481113256s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.101433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:11.275626Z","time spent":"825.746858ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:12.101474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.619555Z","time spent":"1.481891886s","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.101704Z","caller":"traceutil/trace.go:171","msg":"trace[1252517423] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.481562719s","start":"2026-02-10T14:59:10.620132Z","end":"2026-02-10T14:59:12.101695Z","steps":["trace[1252517423] 'process raft request' (duration: 1.480600863s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.101811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.620121Z","time spent":"1.48160503s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.101921Z","caller":"traceutil/trace.go:171","msg":"trace[1390535482] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"826.447667ms","start":"2026-02-10T14:59:11.275461Z","end":"2026-02-10T14:59:12.101908Z","steps":["trace[1390535482] 'process raft request' (duration: 825.520812ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:12.10194Z","caller":"traceutil/trace.go:171","msg":"trace[1521071237] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.481202749s","start":"2026-02-10T14:59:10.620729Z","end":"2026-02-10T14:59:12.101931Z","steps":["trace[1521071237] 'process raft request' (duration: 1.480049928s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.101985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.62072Z","time spent":"1.48124496s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.10211Z","caller":"traceutil/trace.go:171","msg":"trace[712688783] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"1.481012414s","start":"2026-02-10T14:59:10.621087Z","end":"2026-02-10T14:59:12.102099Z","steps":["trace[712688783] 'process raft request' (duration: 1.479714289s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.102168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.621079Z","time spent":"1.481065525s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.101334Z","caller":"traceutil/trace.go:171","msg":"trace[385455951] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.482490463s","start":"2026-02-10T14:59:10.618829Z","end":"2026-02-10T14:59:12.101319Z","steps":["trace[385455951] 'process raft request' (duration: 903.222255ms)","trace[385455951] 'compare' (duration: 578.260912ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:12.102299Z","caller":"traceutil/trace.go:171","msg":"trace[911995957] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.480590983s","start":"2026-02-10T14:59:10.621693Z","end":"2026-02-10T14:59:12.102284Z","steps":["trace[911995957] 'process raft request' (duration: 1.479126843s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.102274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.618821Z","time spent":"1.483420948s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:12.102339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.621687Z","time spent":"1.480633004s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.102432Z","caller":"traceutil/trace.go:171","msg":"trace[880909340] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.480684436s","start":"2026-02-10T14:59:10.621734Z","end":"2026-02-10T14:59:12.102419Z","steps":["trace[880909340] 'process raft request' (duration: 1.479120124s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.102483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.621731Z","time spent":"1.480727417s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.102569Z","caller":"traceutil/trace.go:171","msg":"trace[1000191334] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.480680675s","start":"2026-02-10T14:59:10.621878Z","end":"2026-02-10T14:59:12.102559Z","steps":["trace[1000191334] 'process raft request' (duration: 1.479002391s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.102631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.621871Z","time spent":"1.480725316s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:12.103152Z","caller":"traceutil/trace.go:171","msg":"trace[1521889743] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.481074166s","start":"2026-02-10T14:59:10.622063Z","end":"2026-02-10T14:59:12.103137Z","steps":["trace[1521889743] 'process raft request' (duration: 1.478849787s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.103232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.621992Z","time spent":"1.481193379s","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3167,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:12.103243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.437959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:12.103285Z","caller":"traceutil/trace.go:171","msg":"trace[2084609592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22; }","duration":"906.508791ms","start":"2026-02-10T14:59:11.196763Z","end":"2026-02-10T14:59:12.103272Z","steps":["trace[2084609592] 'agreement among raft nodes before linearized reading' (duration: 906.413179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.103345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:11.196631Z","time spent":"906.706456ms","remote":"127.0.0.1:38594","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-10T14:59:12.102084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:11.275446Z","time spent":"826.508128ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:12.103578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43149842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-10T14:59:12.10361Z","caller":"traceutil/trace.go:171","msg":"trace[1727794131] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"1.43152954s","start":"2026-02-10T14:59:10.67207Z","end":"2026-02-10T14:59:12.1036Z","steps":["trace[1727794131] 'agreement among raft nodes before linearized reading' (duration: 1.431448858s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.10366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.672059Z","time spent":"1.431593623s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:12.103938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436446511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-10T14:59:12.103972Z","caller":"traceutil/trace.go:171","msg":"trace[172987749] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"1.436486082s","start":"2026-02-10T14:59:10.667476Z","end":"2026-02-10T14:59:12.103962Z","steps":["trace[172987749] 'agreement among raft nodes before linearized reading' (duration: 1.436361139s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.10403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.667456Z","time spent":"1.436560345s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:12.10431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484495826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-94pzx\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-02-10T14:59:12.104357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485551245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:12.104409Z","caller":"traceutil/trace.go:171","msg":"trace[506128625] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:22; }","duration":"1.485612436s","start":"2026-02-10T14:59:10.618783Z","end":"2026-02-10T14:59:12.104395Z","steps":["trace[506128625] 'agreement among raft nodes before linearized reading' (duration: 1.485530613s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.104448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.618778Z","time spent":"1.485660047s","remote":"127.0.0.1:38968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-02-10T14:59:12.104607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.760882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:12.104646Z","caller":"traceutil/trace.go:171","msg":"trace[143313635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22; }","duration":"429.832504ms","start":"2026-02-10T14:59:11.674801Z","end":"2026-02-10T14:59:12.104634Z","steps":["trace[143313635] 'agreement among raft nodes before linearized reading' (duration: 429.781473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.104677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:11.674768Z","time spent":"429.901796ms","remote":"127.0.0.1:38608","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-10T14:59:12.104367Z","caller":"traceutil/trace.go:171","msg":"trace[2087788205] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-94pzx; range_end:; response_count:1; response_revision:22; }","duration":"1.484537767s","start":"2026-02-10T14:59:10.619796Z","end":"2026-02-10T14:59:12.104334Z","steps":["trace[2087788205] 'agreement among raft nodes before linearized reading' (duration: 1.484449025s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:12.104826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:10.619793Z","time spent":"1.48502009s","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-94pzx\" "} {"level":"warn","ts":"2026-02-10T14:59:12.562373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.804384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:12.562471Z","caller":"traceutil/trace.go:171","msg":"trace[884456611] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"443.462707ms","start":"2026-02-10T14:59:12.118977Z","end":"2026-02-10T14:59:12.56244Z","steps":["trace[884456611] 'process raft request' (duration: 255.533961ms)","trace[884456611] 'compare' (duration: 187.670939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:12.562512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.118962Z","time spent":"443.534129ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:13.139629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.636415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"warn","ts":"2026-02-10T14:59:13.139998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020603749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.140104Z","caller":"traceutil/trace.go:171","msg":"trace[2001585750] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:26; }","duration":"1.020531238s","start":"2026-02-10T14:59:12.119383Z","end":"2026-02-10T14:59:13.139914Z","steps":["trace[2001585750] 'read index received' (duration: 255.12535ms)","trace[2001585750] 'applied index is now lower than readState.Index' (duration: 765.405097ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:13.14022Z","caller":"traceutil/trace.go:171","msg":"trace[435891683] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"1.020975428s","start":"2026-02-10T14:59:12.119234Z","end":"2026-02-10T14:59:13.140209Z","steps":["trace[435891683] 'process raft request' (duration: 719.690735ms)","trace[435891683] 'compare' (duration: 300.514833ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:13.140032Z","caller":"traceutil/trace.go:171","msg":"trace[166497083] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:30; }","duration":"1.02065578s","start":"2026-02-10T14:59:12.119365Z","end":"2026-02-10T14:59:13.140021Z","steps":["trace[166497083] 'agreement among raft nodes before linearized reading' (duration: 1.020560648s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.140279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.11921Z","time spent":"1.02104219s","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:13.140353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.119361Z","time spent":"1.020956258s","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"info","ts":"2026-02-10T14:59:13.14048Z","caller":"traceutil/trace.go:171","msg":"trace[2057906094] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"1.021158123s","start":"2026-02-10T14:59:12.119312Z","end":"2026-02-10T14:59:13.14047Z","steps":["trace[2057906094] 'process raft request' (duration: 1.020396542s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.140522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.119305Z","time spent":"1.021197865s","remote":"127.0.0.1:38714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.140748Z","caller":"traceutil/trace.go:171","msg":"trace[179626060] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"1.019360186s","start":"2026-02-10T14:59:12.121379Z","end":"2026-02-10T14:59:13.140739Z","steps":["trace[179626060] 'process raft request' (duration: 1.018370329s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.140789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.12137Z","time spent":"1.019399847s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.141194Z","caller":"traceutil/trace.go:171","msg":"trace[523021782] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"1.019755296s","start":"2026-02-10T14:59:12.121416Z","end":"2026-02-10T14:59:13.141171Z","steps":["trace[523021782] 'process raft request' (duration: 1.01836373s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.141318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.121412Z","time spent":"1.019840019s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.14134Z","caller":"traceutil/trace.go:171","msg":"trace[85203859] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"1.019852639s","start":"2026-02-10T14:59:12.121477Z","end":"2026-02-10T14:59:13.14133Z","steps":["trace[85203859] 'process raft request' (duration: 1.01838515s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.141387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.121474Z","time spent":"1.01989152s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.141456Z","caller":"traceutil/trace.go:171","msg":"trace[1096694721] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"1.019992893s","start":"2026-02-10T14:59:12.121454Z","end":"2026-02-10T14:59:13.141447Z","steps":["trace[1096694721] 'process raft request' (duration: 1.01835087s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.141493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.12145Z","time spent":"1.020022944s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.141532Z","caller":"traceutil/trace.go:171","msg":"trace[403483579] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"1.020002873s","start":"2026-02-10T14:59:12.121517Z","end":"2026-02-10T14:59:13.14152Z","steps":["trace[403483579] 'process raft request' (duration: 1.01837001s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.14164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.121512Z","time spent":"1.020070764s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:13.239376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.601395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.239517Z","caller":"traceutil/trace.go:171","msg":"trace[1827363436] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:31; }","duration":"949.771509ms","start":"2026-02-10T14:59:12.289728Z","end":"2026-02-10T14:59:13.2395Z","steps":["trace[1827363436] 'agreement among raft nodes before linearized reading' (duration: 949.549074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.239609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.289715Z","time spent":"949.883192ms","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-02-10T14:59:13.239932Z","caller":"traceutil/trace.go:171","msg":"trace[448751111] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"674.66279ms","start":"2026-02-10T14:59:12.565257Z","end":"2026-02-10T14:59:13.23992Z","steps":["trace[448751111] 'process raft request' (duration: 673.55165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.240133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.565242Z","time spent":"674.799273ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:13.242629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.371237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.242742Z","caller":"traceutil/trace.go:171","msg":"trace[533474128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:31; }","duration":"468.48309ms","start":"2026-02-10T14:59:12.774246Z","end":"2026-02-10T14:59:13.242729Z","steps":["trace[533474128] 'agreement among raft nodes before linearized reading' (duration: 468.350126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.242851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.774202Z","time spent":"468.638004ms","remote":"127.0.0.1:38608","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-10T14:59:13.243112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.326613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.243607Z","caller":"traceutil/trace.go:171","msg":"trace[972711214] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:31; }","duration":"728.844677ms","start":"2026-02-10T14:59:12.514746Z","end":"2026-02-10T14:59:13.243591Z","steps":["trace[972711214] 'agreement among raft nodes before linearized reading' (duration: 728.313733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.243682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.514729Z","time spent":"728.939009ms","remote":"127.0.0.1:38740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-10T14:59:13.246852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111462538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.249226Z","caller":"traceutil/trace.go:171","msg":"trace[2013350122] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:31; }","duration":"1.113867553s","start":"2026-02-10T14:59:12.135325Z","end":"2026-02-10T14:59:13.249193Z","steps":["trace[2013350122] 'agreement among raft nodes before linearized reading' (duration: 1.111444368s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.249343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.135317Z","time spent":"1.113976645s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-02-10T14:59:13.249813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127789682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.249839Z","caller":"traceutil/trace.go:171","msg":"trace[227654860] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:31; }","duration":"1.127832644s","start":"2026-02-10T14:59:12.122Z","end":"2026-02-10T14:59:13.249833Z","steps":["trace[227654860] 'agreement among raft nodes before linearized reading' (duration: 1.117222982s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.249854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.121994Z","time spent":"1.127856234s","remote":"127.0.0.1:38714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-02-10T14:59:13.961658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839414383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.961791Z","caller":"traceutil/trace.go:171","msg":"trace[2040806213] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:31; }","duration":"1.839566408s","start":"2026-02-10T14:59:12.122197Z","end":"2026-02-10T14:59:13.961764Z","steps":["trace[2040806213] 'agreement among raft nodes before linearized reading' (duration: 1.127231998s)","trace[2040806213] 'range keys from in-memory index tree' (duration: 712.163475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:13.961849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.122193Z","time spent":"1.839640989s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-10T14:59:13.961911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73235393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:13.961963Z","caller":"traceutil/trace.go:171","msg":"trace[308943836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:31; }","duration":"1.732423092s","start":"2026-02-10T14:59:12.229523Z","end":"2026-02-10T14:59:13.961946Z","steps":["trace[308943836] 'agreement among raft nodes before linearized reading' (duration: 1.020138014s)","trace[308943836] 'range keys from in-memory index tree' (duration: 712.223687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:13.962002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:12.229509Z","time spent":"1.732483073s","remote":"127.0.0.1:38594","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-10T14:59:13.962292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.869898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:13.962654Z","caller":"traceutil/trace.go:171","msg":"trace[1715048708] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"810.31081ms","start":"2026-02-10T14:59:13.152328Z","end":"2026-02-10T14:59:13.962638Z","steps":["trace[1715048708] 'process raft request' (duration: 810.270329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.96273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.15232Z","time spent":"810.376041ms","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.96291Z","caller":"traceutil/trace.go:171","msg":"trace[685032034] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"813.115963ms","start":"2026-02-10T14:59:13.149779Z","end":"2026-02-10T14:59:13.962895Z","steps":["trace[685032034] 'process raft request' (duration: 530.573297ms)","trace[685032034] 'compare' (duration: 281.230111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:13.962978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.14976Z","time spent":"813.188494ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.96333Z","caller":"traceutil/trace.go:171","msg":"trace[1067327603] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"811.541362ms","start":"2026-02-10T14:59:13.151737Z","end":"2026-02-10T14:59:13.963278Z","steps":["trace[1067327603] 'process raft request' (duration: 810.640757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.9634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.151675Z","time spent":"811.692996ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.963634Z","caller":"traceutil/trace.go:171","msg":"trace[1734378488] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"811.644894ms","start":"2026-02-10T14:59:13.151974Z","end":"2026-02-10T14:59:13.963619Z","steps":["trace[1734378488] 'process raft request' (duration: 810.501514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.963708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.151968Z","time spent":"811.705385ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.963893Z","caller":"traceutil/trace.go:171","msg":"trace[527800246] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"811.8522ms","start":"2026-02-10T14:59:13.152022Z","end":"2026-02-10T14:59:13.963875Z","steps":["trace[527800246] 'process raft request' (duration: 810.501455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.96396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.152017Z","time spent":"811.912102ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:13.964137Z","caller":"traceutil/trace.go:171","msg":"trace[2024109418] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"812.062876ms","start":"2026-02-10T14:59:13.152054Z","end":"2026-02-10T14:59:13.964117Z","steps":["trace[2024109418] 'process raft request' (duration: 810.507775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:13.96422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.15205Z","time spent":"812.129757ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:14.379987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.599625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:14.380275Z","caller":"traceutil/trace.go:171","msg":"trace[768352813] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"1.128985183s","start":"2026-02-10T14:59:13.251264Z","end":"2026-02-10T14:59:14.380249Z","steps":["trace[768352813] 'process raft request' (duration: 923.06363ms)","trace[768352813] 'compare' (duration: 205.450572ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:14.380374Z","caller":"traceutil/trace.go:171","msg":"trace[1434099250] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"408.902459ms","start":"2026-02-10T14:59:13.971404Z","end":"2026-02-10T14:59:14.380307Z","steps":["trace[1434099250] 'process raft request' (duration: 408.862878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.380393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.25124Z","time spent":"1.129104757s","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:14.380448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.971385Z","time spent":"409.033334ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:14.38065Z","caller":"traceutil/trace.go:171","msg":"trace[1459385635] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"1.128940043s","start":"2026-02-10T14:59:13.251702Z","end":"2026-02-10T14:59:14.380642Z","steps":["trace[1459385635] 'process raft request' (duration: 1.128376888s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.380688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.251683Z","time spent":"1.128985835s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:14.380703Z","caller":"traceutil/trace.go:171","msg":"trace[417737186] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"852.284832ms","start":"2026-02-10T14:59:13.528406Z","end":"2026-02-10T14:59:14.380691Z","steps":["trace[417737186] 'process raft request' (duration: 851.81186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.380752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.528382Z","time spent":"852.346774ms","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:14.380826Z","caller":"traceutil/trace.go:171","msg":"trace[1618856428] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"1.129079697s","start":"2026-02-10T14:59:13.251738Z","end":"2026-02-10T14:59:14.380817Z","steps":["trace[1618856428] 'process raft request' (duration: 1.128393498s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.380859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.251734Z","time spent":"1.129109427s","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:14.380902Z","caller":"traceutil/trace.go:171","msg":"trace[784743230] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:35; }","duration":"1.106592999s","start":"2026-02-10T14:59:13.274298Z","end":"2026-02-10T14:59:14.380891Z","steps":["trace[784743230] 'read index received' (duration: 406.063303ms)","trace[784743230] 'applied index is now lower than readState.Index' (duration: 700.526326ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:14.380981Z","caller":"traceutil/trace.go:171","msg":"trace[622016491] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"1.127611978s","start":"2026-02-10T14:59:13.25336Z","end":"2026-02-10T14:59:14.380971Z","steps":["trace[622016491] 'process raft request' (duration: 1.126813646s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.381018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.253353Z","time spent":"1.127648938s","remote":"127.0.0.1:38714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:14.381303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107001381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:14.381341Z","caller":"traceutil/trace.go:171","msg":"trace[1504006037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"1.107041202s","start":"2026-02-10T14:59:13.274288Z","end":"2026-02-10T14:59:14.381329Z","steps":["trace[1504006037] 'agreement among raft nodes before linearized reading' (duration: 1.106882638s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.381371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.274244Z","time spent":"1.107120843s","remote":"127.0.0.1:38594","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-10T14:59:14.381524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075948587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:14.381552Z","caller":"traceutil/trace.go:171","msg":"trace[303840768] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:43; }","duration":"1.076021039s","start":"2026-02-10T14:59:13.305523Z","end":"2026-02-10T14:59:14.381544Z","steps":["trace[303840768] 'agreement among raft nodes before linearized reading' (duration: 1.075977298s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.381577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.3055Z","time spent":"1.076070381s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:14.381751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.249354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:14.38178Z","caller":"traceutil/trace.go:171","msg":"trace[78040250] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:43; }","duration":"410.296898ms","start":"2026-02-10T14:59:13.971473Z","end":"2026-02-10T14:59:14.38177Z","steps":["trace[78040250] 'agreement among raft nodes before linearized reading' (duration: 410.258296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:14.381805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:13.971466Z","time spent":"410.332988ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-02-10T14:59:15.117573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.504721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:15.117657Z","caller":"traceutil/trace.go:171","msg":"trace[1358752185] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:47; }","duration":"723.875106ms","start":"2026-02-10T14:59:14.393767Z","end":"2026-02-10T14:59:15.117642Z","steps":["trace[1358752185] 'read index received' (duration: 267.186208ms)","trace[1358752185] 'applied index is now lower than readState.Index' (duration: 456.687828ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:15.117935Z","caller":"traceutil/trace.go:171","msg":"trace[459125227] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"725.588821ms","start":"2026-02-10T14:59:14.392332Z","end":"2026-02-10T14:59:15.117921Z","steps":["trace[459125227] 'process raft request' (duration: 268.683938ms)","trace[459125227] 'compare' (duration: 456.339597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:15.118004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.39232Z","time spent":"725.651563ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:15.118194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.420389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.118221Z","caller":"traceutil/trace.go:171","msg":"trace[315056634] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:44; }","duration":"724.465991ms","start":"2026-02-10T14:59:14.393746Z","end":"2026-02-10T14:59:15.118212Z","steps":["trace[315056634] 'agreement among raft nodes before linearized reading' (duration: 724.398309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.11825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.393738Z","time spent":"724.503342ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-10T14:59:15.897042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.907114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:4"} {"level":"info","ts":"2026-02-10T14:59:15.897313Z","caller":"traceutil/trace.go:171","msg":"trace[1710212294] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"779.613924ms","start":"2026-02-10T14:59:15.117686Z","end":"2026-02-10T14:59:15.8973Z","steps":["trace[1710212294] 'read index received' (duration: 472.399563ms)","trace[1710212294] 'applied index is now lower than readState.Index' (duration: 307.213351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:15.897737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501881388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.897861Z","caller":"traceutil/trace.go:171","msg":"trace[319158266] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:44; }","duration":"1.502021092s","start":"2026-02-10T14:59:14.395828Z","end":"2026-02-10T14:59:15.897849Z","steps":["trace[319158266] 'agreement among raft nodes before linearized reading' (duration: 1.501876188s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.897946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.395821Z","time spent":"1.502115624s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"info","ts":"2026-02-10T14:59:15.898075Z","caller":"traceutil/trace.go:171","msg":"trace[1960108239] transaction","detail":"{read_only:false; number_of_response:0; response_revision:44; }","duration":"958.776098ms","start":"2026-02-10T14:59:14.939281Z","end":"2026-02-10T14:59:15.898057Z","steps":["trace[1960108239] 'process raft request' (duration: 650.794357ms)","trace[1960108239] 'compare' (duration: 306.851162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:15.897743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449602421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:15.898162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.939267Z","time spent":"958.856269ms","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:15.898181Z","caller":"traceutil/trace.go:171","msg":"trace[79990507] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"1.450091235s","start":"2026-02-10T14:59:14.448082Z","end":"2026-02-10T14:59:15.898173Z","steps":["trace[79990507] 'agreement among raft nodes before linearized reading' (duration: 1.449606332s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.898261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.448027Z","time spent":"1.450225158s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:15.898375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.171269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5872"} {"level":"info","ts":"2026-02-10T14:59:15.89841Z","caller":"traceutil/trace.go:171","msg":"trace[186335997] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:44; }","duration":"959.24986ms","start":"2026-02-10T14:59:14.939151Z","end":"2026-02-10T14:59:15.898401Z","steps":["trace[186335997] 'agreement among raft nodes before linearized reading' (duration: 959.187779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.898438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.939132Z","time spent":"959.299641ms","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5895,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-10T14:59:15.898591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423909629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.898625Z","caller":"traceutil/trace.go:171","msg":"trace[35010945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"1.423977611s","start":"2026-02-10T14:59:14.474638Z","end":"2026-02-10T14:59:15.898615Z","steps":["trace[35010945] 'agreement among raft nodes before linearized reading' (duration: 1.42393461s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.898655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:14.474619Z","time spent":"1.424030783s","remote":"127.0.0.1:38594","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-10T14:59:15.898851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.230384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.901813Z","caller":"traceutil/trace.go:171","msg":"trace[1987296103] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:46; }","duration":"779.207184ms","start":"2026-02-10T14:59:15.122596Z","end":"2026-02-10T14:59:15.901803Z","steps":["trace[1987296103] 'agreement among raft nodes before linearized reading' (duration: 776.184093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.901893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.122589Z","time spent":"779.293696ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-02-10T14:59:15.902236Z","caller":"traceutil/trace.go:171","msg":"trace[1845902050] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"779.870831ms","start":"2026-02-10T14:59:15.122353Z","end":"2026-02-10T14:59:15.902224Z","steps":["trace[1845902050] 'process raft request' (duration: 774.876439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.90243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.122337Z","time spent":"780.041446ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:15.902593Z","caller":"traceutil/trace.go:171","msg":"trace[417357527] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"779.632075ms","start":"2026-02-10T14:59:15.122953Z","end":"2026-02-10T14:59:15.902585Z","steps":["trace[417357527] 'process raft request' (duration: 775.789683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.902676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.122945Z","time spent":"779.704587ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:15.902836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.113756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.902895Z","caller":"traceutil/trace.go:171","msg":"trace[451775594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:46; }","duration":"207.172757ms","start":"2026-02-10T14:59:15.695716Z","end":"2026-02-10T14:59:15.902888Z","steps":["trace[451775594] 'agreement among raft nodes before linearized reading' (duration: 207.099335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.903047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.233723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.903094Z","caller":"traceutil/trace.go:171","msg":"trace[888159281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:46; }","duration":"531.309927ms","start":"2026-02-10T14:59:15.371773Z","end":"2026-02-10T14:59:15.903083Z","steps":["trace[888159281] 'agreement among raft nodes before linearized reading' (duration: 531.237674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.903156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.371767Z","time spent":"531.382579ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T14:59:15.903737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.949872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:15.903814Z","caller":"traceutil/trace.go:171","msg":"trace[2037014032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:46; }","duration":"532.060206ms","start":"2026-02-10T14:59:15.371746Z","end":"2026-02-10T14:59:15.903806Z","steps":["trace[2037014032] 'agreement among raft nodes before linearized reading' (duration: 531.969313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:15.903878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.371729Z","time spent":"532.114948ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T14:59:16.229659Z","caller":"traceutil/trace.go:171","msg":"trace[1071646538] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"326.714629ms","start":"2026-02-10T14:59:15.902923Z","end":"2026-02-10T14:59:16.229638Z","steps":["trace[1071646538] 'process raft request' (duration: 326.601916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.229779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.902905Z","time spent":"326.820112ms","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:16.239814Z","caller":"traceutil/trace.go:171","msg":"trace[1583003879] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"329.04759ms","start":"2026-02-10T14:59:15.910724Z","end":"2026-02-10T14:59:16.239772Z","steps":["trace[1583003879] 'process raft request' (duration: 327.611512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.239976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.910706Z","time spent":"329.193305ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:16.241582Z","caller":"traceutil/trace.go:171","msg":"trace[254356536] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"330.762255ms","start":"2026-02-10T14:59:15.910805Z","end":"2026-02-10T14:59:16.241567Z","steps":["trace[254356536] 'process raft request' (duration: 327.670364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.24165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.9108Z","time spent":"330.822477ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:16.241785Z","caller":"traceutil/trace.go:171","msg":"trace[422961876] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:52; }","duration":"330.878188ms","start":"2026-02-10T14:59:15.910889Z","end":"2026-02-10T14:59:16.241767Z","steps":["trace[422961876] 'read index received' (duration: 319.291051ms)","trace[422961876] 'applied index is now lower than readState.Index' (duration: 11.586627ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:16.241877Z","caller":"traceutil/trace.go:171","msg":"trace[1536910873] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"320.324779ms","start":"2026-02-10T14:59:15.921542Z","end":"2026-02-10T14:59:16.241867Z","steps":["trace[1536910873] 'process raft request' (duration: 320.162194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.241931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.921535Z","time spent":"320.3659ms","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:16.242076Z","caller":"traceutil/trace.go:171","msg":"trace[1332450827] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"328.023354ms","start":"2026-02-10T14:59:15.914044Z","end":"2026-02-10T14:59:16.242067Z","steps":["trace[1332450827] 'process raft request' (duration: 327.452528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.242091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.189777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:16.242116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.914035Z","time spent":"328.061674ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:16.242115Z","caller":"traceutil/trace.go:171","msg":"trace[523633051] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:52; }","duration":"331.242259ms","start":"2026-02-10T14:59:15.910866Z","end":"2026-02-10T14:59:16.242109Z","steps":["trace[523633051] 'agreement among raft nodes before linearized reading' (duration: 331.178127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.242215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.91086Z","time spent":"331.347331ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-02-10T14:59:16.242238Z","caller":"traceutil/trace.go:171","msg":"trace[787433120] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"328.023134ms","start":"2026-02-10T14:59:15.914203Z","end":"2026-02-10T14:59:16.242226Z","steps":["trace[787433120] 'process raft request' (duration: 327.326765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.242293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.914194Z","time spent":"328.073245ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:16.24249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.561046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:16.24251Z","caller":"traceutil/trace.go:171","msg":"trace[300091744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:52; }","duration":"331.591638ms","start":"2026-02-10T14:59:15.910912Z","end":"2026-02-10T14:59:16.242504Z","steps":["trace[300091744] 'agreement among raft nodes before linearized reading' (duration: 331.550927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.242524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.910909Z","time spent":"331.611918ms","remote":"127.0.0.1:38608","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-10T14:59:16.242868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.42106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:16.242889Z","caller":"traceutil/trace.go:171","msg":"trace[169980305] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:52; }","duration":"289.472581ms","start":"2026-02-10T14:59:15.953409Z","end":"2026-02-10T14:59:16.242882Z","steps":["trace[169980305] 'agreement among raft nodes before linearized reading' (duration: 289.44187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.242954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.955208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5488"} {"level":"info","ts":"2026-02-10T14:59:16.242993Z","caller":"traceutil/trace.go:171","msg":"trace[597595185] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:52; }","duration":"331.994689ms","start":"2026-02-10T14:59:15.910988Z","end":"2026-02-10T14:59:16.242982Z","steps":["trace[597595185] 'agreement among raft nodes before linearized reading' (duration: 331.927347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:16.243023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:15.910984Z","time spent":"332.032569ms","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5511,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-10T14:59:23.409036Z","caller":"traceutil/trace.go:171","msg":"trace[1901064231] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"162.596043ms","start":"2026-02-10T14:59:23.246414Z","end":"2026-02-10T14:59:23.40901Z","steps":["trace[1901064231] 'process raft request' (duration: 162.458889ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:23.419359Z","caller":"traceutil/trace.go:171","msg":"trace[275221623] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"141.663998ms","start":"2026-02-10T14:59:23.27759Z","end":"2026-02-10T14:59:23.419254Z","steps":["trace[275221623] 'process raft request' (duration: 141.544145ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:28.706227Z","caller":"traceutil/trace.go:171","msg":"trace[1764439150] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"349.118802ms","start":"2026-02-10T14:59:28.357074Z","end":"2026-02-10T14:59:28.706193Z","steps":["trace[1764439150] 'read index received' (duration: 348.927557ms)","trace[1764439150] 'applied index is now lower than readState.Index' (duration: 190.435µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:28.706495Z","caller":"traceutil/trace.go:171","msg":"trace[1598111298] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"402.289374ms","start":"2026-02-10T14:59:28.3042Z","end":"2026-02-10T14:59:28.706489Z","steps":["trace[1598111298] 'process raft request' (duration: 401.870982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:28.706573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:28.304165Z","time spent":"402.348155ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:28.706784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.690457ms","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-10T14:59:28.706805Z","caller":"traceutil/trace.go:171","msg":"trace[223482210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"349.730588ms","start":"2026-02-10T14:59:28.357068Z","end":"2026-02-10T14:59:28.706798Z","steps":["trace[223482210] 'agreement among raft nodes before linearized reading' (duration: 349.641576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:28.706822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:28.357004Z","time spent":"349.814201ms","remote":"127.0.0.1:38876","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-10T14:59:28.70691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.262596ms","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-10T14:59:28.706938Z","caller":"traceutil/trace.go:171","msg":"trace[555144163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"349.295597ms","start":"2026-02-10T14:59:28.357624Z","end":"2026-02-10T14:59:28.706919Z","steps":["trace[555144163] 'agreement among raft nodes before linearized reading' (duration: 349.266007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:28.706953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:28.357614Z","time spent":"349.335378ms","remote":"127.0.0.1:38876","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-10T14:59:28.926694Z","caller":"traceutil/trace.go:171","msg":"trace[1846259887] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"192.679882ms","start":"2026-02-10T14:59:28.733984Z","end":"2026-02-10T14:59:28.926664Z","steps":["trace[1846259887] 'read index received' (duration: 188.336736ms)","trace[1846259887] 'applied index is now lower than readState.Index' (duration: 4.341505ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:28.926731Z","caller":"traceutil/trace.go:171","msg":"trace[1056956465] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"193.004091ms","start":"2026-02-10T14:59:28.733689Z","end":"2026-02-10T14:59:28.926693Z","steps":["trace[1056956465] 'process raft request' (duration: 188.619025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:28.926926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.904697ms","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-10T14:59:28.926982Z","caller":"traceutil/trace.go:171","msg":"trace[1862337195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"193.017271ms","start":"2026-02-10T14:59:28.733952Z","end":"2026-02-10T14:59:28.92697Z","steps":["trace[1862337195] 'agreement among raft nodes before linearized reading' (duration: 192.836826ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:28.932705Z","caller":"traceutil/trace.go:171","msg":"trace[719910659] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"183.647953ms","start":"2026-02-10T14:59:28.749047Z","end":"2026-02-10T14:59:28.932695Z","steps":["trace[719910659] 'process raft request' (duration: 183.51328ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:28.932941Z","caller":"traceutil/trace.go:171","msg":"trace[279203370] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"182.018959ms","start":"2026-02-10T14:59:28.750902Z","end":"2026-02-10T14:59:28.932921Z","steps":["trace[279203370] 'process raft request' (duration: 181.749912ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:28.933153Z","caller":"traceutil/trace.go:171","msg":"trace[823135044] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"182.161962ms","start":"2026-02-10T14:59:28.750983Z","end":"2026-02-10T14:59:28.933145Z","steps":["trace[823135044] 'process raft request' (duration: 181.862285ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:28.933237Z","caller":"traceutil/trace.go:171","msg":"trace[934164116] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"177.429137ms","start":"2026-02-10T14:59:28.755804Z","end":"2026-02-10T14:59:28.933233Z","steps":["trace[934164116] 'process raft request' (duration: 177.237162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:29.440156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.151388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T14:59:29.440261Z","caller":"traceutil/trace.go:171","msg":"trace[329428917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"227.296181ms","start":"2026-02-10T14:59:29.212948Z","end":"2026-02-10T14:59:29.440244Z","steps":["trace[329428917] 'range keys from in-memory index tree' (duration: 227.081235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:29.440745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.440113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:29.440794Z","caller":"traceutil/trace.go:171","msg":"trace[126041246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"236.528656ms","start":"2026-02-10T14:59:29.204258Z","end":"2026-02-10T14:59:29.440786Z","steps":["trace[126041246] 'range keys from in-memory index tree' (duration: 236.34602ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:37.156919Z","caller":"traceutil/trace.go:171","msg":"trace[1174067429] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"141.698589ms","start":"2026-02-10T14:59:37.015195Z","end":"2026-02-10T14:59:37.156894Z","steps":["trace[1174067429] 'process raft request' (duration: 141.446323ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:37.157122Z","caller":"traceutil/trace.go:171","msg":"trace[767436256] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"140.707694ms","start":"2026-02-10T14:59:37.016389Z","end":"2026-02-10T14:59:37.157096Z","steps":["trace[767436256] 'process raft request' (duration: 140.280833ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:37.157313Z","caller":"traceutil/trace.go:171","msg":"trace[1331712052] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"144.389002ms","start":"2026-02-10T14:59:37.012916Z","end":"2026-02-10T14:59:37.157305Z","steps":["trace[1331712052] 'process raft request' (duration: 143.578219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:38.58582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.923904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:38.585919Z","caller":"traceutil/trace.go:171","msg":"trace[941585498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"808.053478ms","start":"2026-02-10T14:59:37.777847Z","end":"2026-02-10T14:59:38.5859Z","steps":["trace[941585498] 'range keys from in-memory index tree' (duration: 807.842043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:38.585958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:37.777794Z","time spent":"808.15651ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T14:59:39.46466Z","caller":"traceutil/trace.go:171","msg":"trace[1815596270] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"296.886467ms","start":"2026-02-10T14:59:39.167749Z","end":"2026-02-10T14:59:39.464636Z","steps":["trace[1815596270] 'process raft request' (duration: 296.854146ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:39.46496Z","caller":"traceutil/trace.go:171","msg":"trace[1653631220] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"297.998837ms","start":"2026-02-10T14:59:39.166916Z","end":"2026-02-10T14:59:39.464915Z","steps":["trace[1653631220] 'process raft request' (duration: 297.536544ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:39.579921Z","caller":"traceutil/trace.go:171","msg":"trace[764448779] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"218.103297ms","start":"2026-02-10T14:59:39.361793Z","end":"2026-02-10T14:59:39.579896Z","steps":["trace[764448779] 'process raft request' (duration: 150.431401ms)","trace[764448779] 'compare' (duration: 67.378468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:41.886361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.901801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:41.886447Z","caller":"traceutil/trace.go:171","msg":"trace[1527939822] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"289.176992ms","start":"2026-02-10T14:59:41.597256Z","end":"2026-02-10T14:59:41.886433Z","steps":["trace[1527939822] 'read index received' (duration: 186.125117ms)","trace[1527939822] 'applied index is now lower than readState.Index' (duration: 103.051035ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:41.886584Z","caller":"traceutil/trace.go:171","msg":"trace[1611916724] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"380.984778ms","start":"2026-02-10T14:59:41.505592Z","end":"2026-02-10T14:59:41.886576Z","steps":["trace[1611916724] 'process raft request' (duration: 277.7952ms)","trace[1611916724] 'compare' (duration: 102.732476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:41.886618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.505585Z","time spent":"381.016219ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:41.886744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.49374ms","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-10T14:59:41.886759Z","caller":"traceutil/trace.go:171","msg":"trace[922206528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"289.542002ms","start":"2026-02-10T14:59:41.597211Z","end":"2026-02-10T14:59:41.886753Z","steps":["trace[922206528] 'agreement among raft nodes before linearized reading' (duration: 289.509222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:41.886829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.415106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:41.88684Z","caller":"traceutil/trace.go:171","msg":"trace[260208836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"108.466428ms","start":"2026-02-10T14:59:41.77837Z","end":"2026-02-10T14:59:41.886837Z","steps":["trace[260208836] 'agreement among raft nodes before linearized reading' (duration: 108.447507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.263002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.808629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:42.263324Z","caller":"traceutil/trace.go:171","msg":"trace[489757456] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"371.430395ms","start":"2026-02-10T14:59:41.89187Z","end":"2026-02-10T14:59:42.263301Z","steps":["trace[489757456] 'process raft request' (duration: 156.192154ms)","trace[489757456] 'compare' (duration: 214.463251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:42.263396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.891849Z","time spent":"371.513568ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:43.024903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.003974ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T14:59:43.025Z","caller":"traceutil/trace.go:171","msg":"trace[2001706805] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"243.8896ms","start":"2026-02-10T14:59:42.781095Z","end":"2026-02-10T14:59:43.024985Z","steps":["trace[2001706805] 'read index received' (duration: 57.531µs)","trace[2001706805] 'applied index is now lower than readState.Index' (duration: 243.830609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:43.025061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.954413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:43.025075Z","caller":"traceutil/trace.go:171","msg":"trace[2014847821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"244.009244ms","start":"2026-02-10T14:59:42.781059Z","end":"2026-02-10T14:59:43.025069Z","steps":["trace[2014847821] 'agreement among raft nodes before linearized reading' (duration: 243.959213ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:43.477019Z","caller":"traceutil/trace.go:171","msg":"trace[2046583206] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"225.963955ms","start":"2026-02-10T14:59:43.250769Z","end":"2026-02-10T14:59:43.476733Z","steps":["trace[2046583206] 'process raft request' (duration: 225.774581ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:43.763234Z","caller":"traceutil/trace.go:171","msg":"trace[499997296] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"124.136743ms","start":"2026-02-10T14:59:43.639072Z","end":"2026-02-10T14:59:43.763209Z","steps":["trace[499997296] 'process raft request' (duration: 123.991829ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:44.092974Z","caller":"traceutil/trace.go:171","msg":"trace[563132339] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"200.473499ms","start":"2026-02-10T14:59:43.892473Z","end":"2026-02-10T14:59:44.092947Z","steps":["trace[563132339] 'read index received' (duration: 125.420608ms)","trace[563132339] 'applied index is now lower than readState.Index' (duration: 75.051281ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:44.09327Z","caller":"traceutil/trace.go:171","msg":"trace[337206968] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"267.834437ms","start":"2026-02-10T14:59:43.825394Z","end":"2026-02-10T14:59:44.093229Z","steps":["trace[337206968] 'process raft request' (duration: 192.58378ms)","trace[337206968] 'compare' (duration: 74.814905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:44.093745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.257289ms","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-10T14:59:44.093824Z","caller":"traceutil/trace.go:171","msg":"trace[1510907782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"201.396453ms","start":"2026-02-10T14:59:43.892412Z","end":"2026-02-10T14:59:44.093808Z","steps":["trace[1510907782] 'agreement among raft nodes before linearized reading' (duration: 201.268339ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:44.389929Z","caller":"traceutil/trace.go:171","msg":"trace[1022818186] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"155.110565ms","start":"2026-02-10T14:59:44.234786Z","end":"2026-02-10T14:59:44.389896Z","steps":["trace[1022818186] 'read index received' (duration: 97.079716ms)","trace[1022818186] 'applied index is now lower than readState.Index' (duration: 58.029699ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:44.390086Z","caller":"traceutil/trace.go:171","msg":"trace[177548835] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"289.508471ms","start":"2026-02-10T14:59:44.100566Z","end":"2026-02-10T14:59:44.390075Z","steps":["trace[177548835] 'process raft request' (duration: 231.366389ms)","trace[177548835] 'compare' (duration: 57.726751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:44.39036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.337162ms","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-10T14:59:44.390443Z","caller":"traceutil/trace.go:171","msg":"trace[104184129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"123.458845ms","start":"2026-02-10T14:59:44.266963Z","end":"2026-02-10T14:59:44.390422Z","steps":["trace[104184129] 'agreement among raft nodes before linearized reading' (duration: 123.324642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.274066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-10T14:59:44.391114Z","caller":"traceutil/trace.go:171","msg":"trace[1721842858] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:475; }","duration":"156.352148ms","start":"2026-02-10T14:59:44.234748Z","end":"2026-02-10T14:59:44.3911Z","steps":["trace[1721842858] 'agreement among raft nodes before linearized reading' (duration: 156.239945ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:44.632142Z","caller":"traceutil/trace.go:171","msg":"trace[1641114221] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"215.295252ms","start":"2026-02-10T14:59:44.416813Z","end":"2026-02-10T14:59:44.632108Z","steps":["trace[1641114221] 'read index received' (duration: 214.998554ms)","trace[1641114221] 'applied index is now lower than readState.Index' (duration: 295.818µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:44.632357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.512408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-10T14:59:44.632395Z","caller":"traceutil/trace.go:171","msg":"trace[610520324] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:476; }","duration":"215.623821ms","start":"2026-02-10T14:59:44.416758Z","end":"2026-02-10T14:59:44.632382Z","steps":["trace[610520324] 'agreement among raft nodes before linearized reading' (duration: 215.470927ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:44.632713Z","caller":"traceutil/trace.go:171","msg":"trace[1090998949] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"234.886001ms","start":"2026-02-10T14:59:44.397815Z","end":"2026-02-10T14:59:44.632701Z","steps":["trace[1090998949] 'process raft request' (duration: 234.0877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.134439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.952675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:45.134686Z","caller":"traceutil/trace.go:171","msg":"trace[44882510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"350.250903ms","start":"2026-02-10T14:59:44.784418Z","end":"2026-02-10T14:59:45.134669Z","steps":["trace[44882510] 'range keys from in-memory index tree' (duration: 349.886823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.134793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.784398Z","time spent":"350.384946ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T14:59:45.597805Z","caller":"traceutil/trace.go:171","msg":"trace[1863999014] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"109.469774ms","start":"2026-02-10T14:59:45.488297Z","end":"2026-02-10T14:59:45.597767Z","steps":["trace[1863999014] 'process raft request' (duration: 108.898719ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:45.989753Z","caller":"traceutil/trace.go:171","msg":"trace[161145163] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"221.289181ms","start":"2026-02-10T14:59:45.768443Z","end":"2026-02-10T14:59:45.989732Z","steps":["trace[161145163] 'read index received' (duration: 220.799408ms)","trace[161145163] 'applied index is now lower than readState.Index' (duration: 488.543µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:45.989932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.470245ms","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-10T14:59:45.989957Z","caller":"traceutil/trace.go:171","msg":"trace[198258795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"221.56682ms","start":"2026-02-10T14:59:45.76838Z","end":"2026-02-10T14:59:45.989947Z","steps":["trace[198258795] 'agreement among raft nodes before linearized reading' (duration: 221.432126ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:45.990237Z","caller":"traceutil/trace.go:171","msg":"trace[2115681047] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"387.783759ms","start":"2026-02-10T14:59:45.602444Z","end":"2026-02-10T14:59:45.990228Z","steps":["trace[2115681047] 'process raft request' (duration: 387.021088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.990485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.602419Z","time spent":"388.022856ms","remote":"127.0.0.1:38702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317280,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:46.430802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.551711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:46.431078Z","caller":"traceutil/trace.go:171","msg":"trace[1475053018] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"423.111887ms","start":"2026-02-10T14:59:46.007936Z","end":"2026-02-10T14:59:46.431048Z","steps":["trace[1475053018] 'process raft request' (duration: 57.218567ms)","trace[1475053018] 'compare' (duration: 365.440757ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:46.431183Z","caller":"traceutil/trace.go:171","msg":"trace[2073753382] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"412.368142ms","start":"2026-02-10T14:59:46.018795Z","end":"2026-02-10T14:59:46.431163Z","steps":["trace[2073753382] 'process raft request' (duration: 412.100295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:46.431262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.01877Z","time spent":"412.469664ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:46.431252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.007909Z","time spent":"423.23664ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:46.431359Z","caller":"traceutil/trace.go:171","msg":"trace[526893180] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"412.490885ms","start":"2026-02-10T14:59:46.018863Z","end":"2026-02-10T14:59:46.431354Z","steps":["trace[526893180] 'process raft request' (duration: 412.076045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:46.431386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.01886Z","time spent":"412.511716ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:47.279202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.068405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:47.279312Z","caller":"traceutil/trace.go:171","msg":"trace[1950991402] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:500; }","duration":"640.628567ms","start":"2026-02-10T14:59:46.638673Z","end":"2026-02-10T14:59:47.279302Z","steps":["trace[1950991402] 'read index received' (duration: 295.430527ms)","trace[1950991402] 'applied index is now lower than readState.Index' (duration: 345.19737ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:47.279414Z","caller":"traceutil/trace.go:171","msg":"trace[436891268] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"843.842649ms","start":"2026-02-10T14:59:46.435565Z","end":"2026-02-10T14:59:47.279408Z","steps":["trace[436891268] 'process raft request' (duration: 498.525077ms)","trace[436891268] 'compare' (duration: 344.88039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.279443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.435547Z","time spent":"843.8806ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:47.279561Z","caller":"traceutil/trace.go:171","msg":"trace[621898598] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"841.280551ms","start":"2026-02-10T14:59:46.438276Z","end":"2026-02-10T14:59:47.279556Z","steps":["trace[621898598] 'process raft request' (duration: 840.994954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.279583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.438244Z","time spent":"841.327592ms","remote":"127.0.0.1:38714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:47.279649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.998626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T14:59:47.279662Z","caller":"traceutil/trace.go:171","msg":"trace[395053303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"641.201142ms","start":"2026-02-10T14:59:46.638457Z","end":"2026-02-10T14:59:47.279658Z","steps":["trace[395053303] 'agreement among raft nodes before linearized reading' (duration: 641.173802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.279675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.638428Z","time spent":"641.243843ms","remote":"127.0.0.1:38876","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-10T14:59:47.279735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.200502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:47.279747Z","caller":"traceutil/trace.go:171","msg":"trace[1390969750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"468.256104ms","start":"2026-02-10T14:59:46.811487Z","end":"2026-02-10T14:59:47.279743Z","steps":["trace[1390969750] 'agreement among raft nodes before linearized reading' (duration: 468.237753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.27976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.811458Z","time spent":"468.297905ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:47.279879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.819161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T14:59:47.279893Z","caller":"traceutil/trace.go:171","msg":"trace[1243389082] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:483; }","duration":"277.872692ms","start":"2026-02-10T14:59:47.002017Z","end":"2026-02-10T14:59:47.27989Z","steps":["trace[1243389082] 'agreement among raft nodes before linearized reading' (duration: 277.841492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.834935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.32394ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-10T14:59:47.835181Z","caller":"traceutil/trace.go:171","msg":"trace[1130964936] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:503; }","duration":"223.660364ms","start":"2026-02-10T14:59:47.611502Z","end":"2026-02-10T14:59:47.835162Z","steps":["trace[1130964936] 'read index received' (duration: 65.082µs)","trace[1130964936] 'applied index is now lower than readState.Index' (duration: 223.594452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.835147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.28728Z","time spent":"547.859486ms","remote":"127.0.0.1:38642","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T14:59:47.835337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.827128ms","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-10T14:59:47.835365Z","caller":"traceutil/trace.go:171","msg":"trace[382187717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"223.91817ms","start":"2026-02-10T14:59:47.611438Z","end":"2026-02-10T14:59:47.835356Z","steps":["trace[382187717] 'agreement among raft nodes before linearized reading' (duration: 223.773546ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:47.835617Z","caller":"traceutil/trace.go:171","msg":"trace[423864795] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"548.202834ms","start":"2026-02-10T14:59:47.287403Z","end":"2026-02-10T14:59:47.835606Z","steps":["trace[423864795] 'process raft request' (duration: 547.627579ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:47.835727Z","caller":"traceutil/trace.go:171","msg":"trace[746094215] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"548.199904ms","start":"2026-02-10T14:59:47.287483Z","end":"2026-02-10T14:59:47.835683Z","steps":["trace[746094215] 'process raft request' (duration: 547.64593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.836506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.287473Z","time spent":"548.292266ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:47.835681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.287397Z","time spent":"548.244625ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:48.021922Z","caller":"traceutil/trace.go:171","msg":"trace[51858922] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"170.896963ms","start":"2026-02-10T14:59:47.851001Z","end":"2026-02-10T14:59:48.021898Z","steps":["trace[51858922] 'process raft request' (duration: 170.506844ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.022249Z","caller":"traceutil/trace.go:171","msg":"trace[296011721] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"168.702166ms","start":"2026-02-10T14:59:47.853533Z","end":"2026-02-10T14:59:48.022235Z","steps":["trace[296011721] 'process raft request' (duration: 168.0885ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.022445Z","caller":"traceutil/trace.go:171","msg":"trace[1105559530] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"168.458ms","start":"2026-02-10T14:59:47.853966Z","end":"2026-02-10T14:59:48.022424Z","steps":["trace[1105559530] 'process raft request' (duration: 168.08068ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.155696Z","caller":"traceutil/trace.go:171","msg":"trace[582412945] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"100.810895ms","start":"2026-02-10T14:59:48.054868Z","end":"2026-02-10T14:59:48.155679Z","steps":["trace[582412945] 'process raft request' (duration: 90.156772ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.434211Z","caller":"traceutil/trace.go:171","msg":"trace[106984089] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"269.768367ms","start":"2026-02-10T14:59:48.163625Z","end":"2026-02-10T14:59:48.433394Z","steps":["trace[106984089] 'process raft request' (duration: 269.448269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.905064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.092063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:48.905355Z","caller":"traceutil/trace.go:171","msg":"trace[1812579694] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:514; }","duration":"106.456804ms","start":"2026-02-10T14:59:48.79889Z","end":"2026-02-10T14:59:48.905347Z","steps":["trace[1812579694] 'read index received' (duration: 106.352131ms)","trace[1812579694] 'applied index is now lower than readState.Index' (duration: 104.203µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:48.90542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.531856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:48.905437Z","caller":"traceutil/trace.go:171","msg":"trace[23903275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"106.583477ms","start":"2026-02-10T14:59:48.798847Z","end":"2026-02-10T14:59:48.905431Z","steps":["trace[23903275] 'agreement among raft nodes before linearized reading' (duration: 106.531646ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.90562Z","caller":"traceutil/trace.go:171","msg":"trace[1687298216] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"459.459631ms","start":"2026-02-10T14:59:48.446154Z","end":"2026-02-10T14:59:48.905614Z","steps":["trace[1687298216] 'process raft request' (duration: 459.121201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.905655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.445986Z","time spent":"459.651585ms","remote":"127.0.0.1:39116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:48.905815Z","caller":"traceutil/trace.go:171","msg":"trace[618503299] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"457.027046ms","start":"2026-02-10T14:59:48.448782Z","end":"2026-02-10T14:59:48.905809Z","steps":["trace[618503299] 'process raft request' (duration: 456.532052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.905855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.448769Z","time spent":"457.062577ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:48.905184Z","caller":"traceutil/trace.go:171","msg":"trace[1571849646] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"460.644231ms","start":"2026-02-10T14:59:48.444523Z","end":"2026-02-10T14:59:48.905167Z","steps":["trace[1571849646] 'process raft request' (duration: 299.313602ms)","trace[1571849646] 'compare' (duration: 160.97675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:48.911909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.444511Z","time spent":"467.338099ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:49.185262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.617414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:49.185398Z","caller":"traceutil/trace.go:171","msg":"trace[1539359735] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"269.11002ms","start":"2026-02-10T14:59:48.91627Z","end":"2026-02-10T14:59:49.18538Z","steps":["trace[1539359735] 'process raft request' (duration: 117.062995ms)","trace[1539359735] 'compare' (duration: 151.448149ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:49.208501Z","caller":"traceutil/trace.go:171","msg":"trace[681825937] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"222.639646ms","start":"2026-02-10T14:59:48.985847Z","end":"2026-02-10T14:59:49.208486Z","steps":["trace[681825937] 'process raft request' (duration: 222.480672ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:49.208526Z","caller":"traceutil/trace.go:171","msg":"trace[793785852] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"289.576723ms","start":"2026-02-10T14:59:48.918922Z","end":"2026-02-10T14:59:49.208498Z","steps":["trace[793785852] 'process raft request' (duration: 289.101371ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:54.437989Z","caller":"traceutil/trace.go:171","msg":"trace[1235469567] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"137.711004ms","start":"2026-02-10T14:59:54.300257Z","end":"2026-02-10T14:59:54.437968Z","steps":["trace[1235469567] 'process raft request' (duration: 137.569011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:59.506367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841754022,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:00:00.007056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841754022,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:00:00.067334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.062438618s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:00:00.06769Z","caller":"traceutil/trace.go:171","msg":"trace[1756798480] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.062990473s","start":"2026-02-10T14:59:59.004679Z","end":"2026-02-10T15:00:00.067669Z","steps":["trace[1756798480] 'process raft request' (duration: 1.06288087s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.06779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.004654Z","time spent":"1.063073104s","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:00.53241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.474609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:00:00.532481Z","caller":"traceutil/trace.go:171","msg":"trace[820090222] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"1.52647896s","start":"2026-02-10T14:59:59.00599Z","end":"2026-02-10T15:00:00.532469Z","steps":["trace[820090222] 'read index received' (duration: 1.061660527s)","trace[820090222] 'applied index is now lower than readState.Index' (duration: 464.817383ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:00.532561Z","caller":"traceutil/trace.go:171","msg":"trace[1485128869] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"614.38108ms","start":"2026-02-10T14:59:59.918171Z","end":"2026-02-10T15:00:00.532552Z","steps":["trace[1485128869] 'process raft request' (duration: 237.422269ms)","trace[1485128869] 'compare' (duration: 376.399537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:00.532609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.918155Z","time spent":"614.429482ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:00.532801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526815289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9qdld\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T15:00:00.532827Z","caller":"traceutil/trace.go:171","msg":"trace[1419027365] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9qdld; range_end:; response_count:1; response_revision:545; }","duration":"1.52684036s","start":"2026-02-10T14:59:59.00598Z","end":"2026-02-10T15:00:00.53282Z","steps":["trace[1419027365] 'agreement among raft nodes before linearized reading' (duration: 1.526750407s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.532847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.005974Z","time spent":"1.52686821s","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-9qdld\" "} {"level":"warn","ts":"2026-02-10T15:00:00.532995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113102222s","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-10T15:00:00.533019Z","caller":"traceutil/trace.go:171","msg":"trace[349674781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"1.113176414s","start":"2026-02-10T14:59:59.419836Z","end":"2026-02-10T15:00:00.533013Z","steps":["trace[349674781] 'agreement among raft nodes before linearized reading' (duration: 1.113117043s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.533036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.419806Z","time spent":"1.113226605s","remote":"127.0.0.1:38876","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-10T15:00:00.533267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.762282ms","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-10T15:00:00.533296Z","caller":"traceutil/trace.go:171","msg":"trace[299321123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"458.814252ms","start":"2026-02-10T15:00:00.074473Z","end":"2026-02-10T15:00:00.533287Z","steps":["trace[299321123] 'agreement among raft nodes before linearized reading' (duration: 458.695469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.533326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:00.07446Z","time spent":"458.861924ms","remote":"127.0.0.1:38876","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-10T15:00:00.533433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106567279s","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-10T15:00:00.533454Z","caller":"traceutil/trace.go:171","msg":"trace[702973533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.1066256s","start":"2026-02-10T14:59:59.426823Z","end":"2026-02-10T15:00:00.533449Z","steps":["trace[702973533] 'agreement among raft nodes before linearized reading' (duration: 1.106573589s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.53347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.426801Z","time spent":"1.106665372s","remote":"127.0.0.1:38876","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-10T15:00:00.841942Z","caller":"traceutil/trace.go:171","msg":"trace[706607115] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"298.239113ms","start":"2026-02-10T15:00:00.543681Z","end":"2026-02-10T15:00:00.84192Z","steps":["trace[706607115] 'process raft request' (duration: 297.602856ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:00.842124Z","caller":"traceutil/trace.go:171","msg":"trace[1877499637] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"297.01488ms","start":"2026-02-10T15:00:00.545097Z","end":"2026-02-10T15:00:00.842112Z","steps":["trace[1877499637] 'process raft request' (duration: 296.325882ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:00.842846Z","caller":"traceutil/trace.go:171","msg":"trace[1446291309] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"294.559644ms","start":"2026-02-10T15:00:00.548271Z","end":"2026-02-10T15:00:00.842831Z","steps":["trace[1446291309] 'process raft request' (duration: 293.200749ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:01.227112Z","caller":"traceutil/trace.go:171","msg":"trace[220312121] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"123.341172ms","start":"2026-02-10T15:00:01.103749Z","end":"2026-02-10T15:00:01.227091Z","steps":["trace[220312121] 'process raft request' (duration: 119.212023ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:02.182524Z","caller":"traceutil/trace.go:171","msg":"trace[1149794800] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"107.749089ms","start":"2026-02-10T15:00:02.074758Z","end":"2026-02-10T15:00:02.182508Z","steps":["trace[1149794800] 'process raft request' (duration: 107.629596ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:20.448141Z","caller":"traceutil/trace.go:171","msg":"trace[195790865] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"128.273747ms","start":"2026-02-10T15:00:20.319849Z","end":"2026-02-10T15:00:20.448123Z","steps":["trace[195790865] 'read index received' (duration: 128.125824ms)","trace[195790865] 'applied index is now lower than readState.Index' (duration: 147.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:20.448327Z","caller":"traceutil/trace.go:171","msg":"trace[472131458] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"262.255254ms","start":"2026-02-10T15:00:20.186063Z","end":"2026-02-10T15:00:20.448318Z","steps":["trace[472131458] 'process raft request' (duration: 261.969116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:20.448492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.626747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:20.448516Z","caller":"traceutil/trace.go:171","msg":"trace[919305466] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:734; }","duration":"128.72471ms","start":"2026-02-10T15:00:20.319783Z","end":"2026-02-10T15:00:20.448508Z","steps":["trace[919305466] 'agreement among raft nodes before linearized reading' (duration: 128.669819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:20.448683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.009302ms","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-10T15:00:20.448701Z","caller":"traceutil/trace.go:171","msg":"trace[1409448414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"125.040224ms","start":"2026-02-10T15:00:20.323656Z","end":"2026-02-10T15:00:20.448696Z","steps":["trace[1409448414] 'agreement among raft nodes before linearized reading' (duration: 124.966082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:22.642276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.540922ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:00:22.642399Z","caller":"traceutil/trace.go:171","msg":"trace[794927082] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"149.717707ms","start":"2026-02-10T15:00:22.492665Z","end":"2026-02-10T15:00:22.642383Z","steps":["trace[794927082] 'read index received' (duration: 5.024011ms)","trace[794927082] 'applied index is now lower than readState.Index' (duration: 144.692246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:22.642737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.062636ms","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-10T15:00:22.642774Z","caller":"traceutil/trace.go:171","msg":"trace[1344193654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"150.140937ms","start":"2026-02-10T15:00:22.492622Z","end":"2026-02-10T15:00:22.642763Z","steps":["trace[1344193654] 'agreement among raft nodes before linearized reading' (duration: 149.971593ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:23.124287Z","caller":"traceutil/trace.go:171","msg":"trace[162409472] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"343.139934ms","start":"2026-02-10T15:00:22.781131Z","end":"2026-02-10T15:00:23.124271Z","steps":["trace[162409472] 'read index received' (duration: 335.186726ms)","trace[162409472] 'applied index is now lower than readState.Index' (duration: 7.952508ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:23.124457Z","caller":"traceutil/trace.go:171","msg":"trace[867175880] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"424.524948ms","start":"2026-02-10T15:00:22.699924Z","end":"2026-02-10T15:00:23.124449Z","steps":["trace[867175880] 'process raft request' (duration: 416.440777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:23.124549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:22.699912Z","time spent":"424.57692ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:23.124684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.592275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:23.12471Z","caller":"traceutil/trace.go:171","msg":"trace[1585000849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"343.652957ms","start":"2026-02-10T15:00:22.78105Z","end":"2026-02-10T15:00:23.124703Z","steps":["trace[1585000849] 'agreement among raft nodes before linearized reading' (duration: 343.614076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:23.124728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:22.78103Z","time spent":"343.694138ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T15:00:23.124861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.86498ms","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-10T15:00:23.124885Z","caller":"traceutil/trace.go:171","msg":"trace[624090588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"234.91094ms","start":"2026-02-10T15:00:22.889967Z","end":"2026-02-10T15:00:23.124878Z","steps":["trace[624090588] 'agreement among raft nodes before linearized reading' (duration: 234.836848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:23.125031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.861642ms","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-10T15:00:23.125053Z","caller":"traceutil/trace.go:171","msg":"trace[1718673872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"136.902083ms","start":"2026-02-10T15:00:22.988146Z","end":"2026-02-10T15:00:23.125048Z","steps":["trace[1718673872] 'agreement among raft nodes before linearized reading' (duration: 136.844372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:23.125181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.630816ms","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-10T15:00:23.125208Z","caller":"traceutil/trace.go:171","msg":"trace[542528443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"164.680807ms","start":"2026-02-10T15:00:22.960521Z","end":"2026-02-10T15:00:23.125202Z","steps":["trace[542528443] 'agreement among raft nodes before linearized reading' (duration: 164.625026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:23.332497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.732392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:00:23.332716Z","caller":"traceutil/trace.go:171","msg":"trace[1128782105] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"193.961793ms","start":"2026-02-10T15:00:23.138739Z","end":"2026-02-10T15:00:23.332701Z","steps":["trace[1128782105] 'process raft request' (duration: 193.923141ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:23.333139Z","caller":"traceutil/trace.go:171","msg":"trace[1987191555] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"197.219897ms","start":"2026-02-10T15:00:23.135865Z","end":"2026-02-10T15:00:23.333085Z","steps":["trace[1987191555] 'process raft request' (duration: 91.830628ms)","trace[1987191555] 'compare' (duration: 104.64587ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:23.333314Z","caller":"traceutil/trace.go:171","msg":"trace[243717853] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"195.804289ms","start":"2026-02-10T15:00:23.137498Z","end":"2026-02-10T15:00:23.333303Z","steps":["trace[243717853] 'process raft request' (duration: 195.110751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.115991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.393993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:00:41.116384Z","caller":"traceutil/trace.go:171","msg":"trace[303594586] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"276.094934ms","start":"2026-02-10T15:00:40.840233Z","end":"2026-02-10T15:00:41.116328Z","steps":["trace[303594586] 'process raft request' (duration: 99.073775ms)","trace[303594586] 'compare' (duration: 176.007423ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:41.54848Z","caller":"traceutil/trace.go:171","msg":"trace[1368325996] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"104.842007ms","start":"2026-02-10T15:00:41.443618Z","end":"2026-02-10T15:00:41.54846Z","steps":["trace[1368325996] 'process raft request' (duration: 76.464396ms)","trace[1368325996] 'compare' (duration: 28.197335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:53.825944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.845636ms","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-10T15:00:53.826004Z","caller":"traceutil/trace.go:171","msg":"trace[512779532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"214.93944ms","start":"2026-02-10T15:00:53.61105Z","end":"2026-02-10T15:00:53.82599Z","steps":["trace[512779532] 'range keys from in-memory index tree' (duration: 214.705342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:53.826159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.11091ms","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-10T15:00:53.826179Z","caller":"traceutil/trace.go:171","msg":"trace[1063441455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"152.199192ms","start":"2026-02-10T15:00:53.673972Z","end":"2026-02-10T15:00:53.826171Z","steps":["trace[1063441455] 'range keys from in-memory index tree' (duration: 151.972186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:53.826326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.487919ms","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-10T15:00:53.826345Z","caller":"traceutil/trace.go:171","msg":"trace[1898523437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"142.53017ms","start":"2026-02-10T15:00:53.683809Z","end":"2026-02-10T15:00:53.82634Z","steps":["trace[1898523437] 'range keys from in-memory index tree' (duration: 142.383685ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:02.193558Z","caller":"traceutil/trace.go:171","msg":"trace[1397650413] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"320.195187ms","start":"2026-02-10T15:01:01.873339Z","end":"2026-02-10T15:01:02.193535Z","steps":["trace[1397650413] 'process raft request' (duration: 320.077743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:02.193704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:01.873322Z","time spent":"320.32143ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:02.193881Z","caller":"traceutil/trace.go:171","msg":"trace[455617382] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:948; }","duration":"316.411807ms","start":"2026-02-10T15:01:01.87745Z","end":"2026-02-10T15:01:02.193862Z","steps":["trace[455617382] 'read index received' (duration: 316.405127ms)","trace[455617382] 'applied index is now lower than readState.Index' (duration: 5.03µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:02.194088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.056497ms","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-10T15:01:02.194123Z","caller":"traceutil/trace.go:171","msg":"trace[841723151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"311.117509ms","start":"2026-02-10T15:01:01.882998Z","end":"2026-02-10T15:01:02.194115Z","steps":["trace[841723151] 'agreement among raft nodes before linearized reading' (duration: 310.956855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:02.194151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:01.882993Z","time spent":"311.15189ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T15:01:02.194189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.731275ms","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-10T15:01:02.194233Z","caller":"traceutil/trace.go:171","msg":"trace[1077210611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"316.781648ms","start":"2026-02-10T15:01:01.877438Z","end":"2026-02-10T15:01:02.19422Z","steps":["trace[1077210611] 'agreement among raft nodes before linearized reading' (duration: 316.655364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:02.194265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:01.877433Z","time spent":"316.824439ms","remote":"127.0.0.1:38876","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-10T15:01:02.194331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.021315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:02.194352Z","caller":"traceutil/trace.go:171","msg":"trace[1497742788] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:911; }","duration":"214.067477ms","start":"2026-02-10T15:01:01.980279Z","end":"2026-02-10T15:01:02.194346Z","steps":["trace[1497742788] 'agreement among raft nodes before linearized reading' (duration: 214.030726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:02.194467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.017066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:02.194495Z","caller":"traceutil/trace.go:171","msg":"trace[581949018] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:911; }","duration":"301.068636ms","start":"2026-02-10T15:01:01.89342Z","end":"2026-02-10T15:01:02.194489Z","steps":["trace[581949018] 'agreement among raft nodes before linearized reading' (duration: 301.027105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:02.194517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:01.89341Z","time spent":"301.101848ms","remote":"127.0.0.1:38848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:01:03.839626Z","caller":"traceutil/trace.go:171","msg":"trace[1436366111] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"249.687407ms","start":"2026-02-10T15:01:03.589916Z","end":"2026-02-10T15:01:03.839603Z","steps":["trace[1436366111] 'process raft request' (duration: 249.304486ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:08.487781Z","caller":"traceutil/trace.go:171","msg":"trace[1292406679] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"247.662933ms","start":"2026-02-10T15:01:08.240103Z","end":"2026-02-10T15:01:08.487766Z","steps":["trace[1292406679] 'read index received' (duration: 247.52748ms)","trace[1292406679] 'applied index is now lower than readState.Index' (duration: 134.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:08.487991Z","caller":"traceutil/trace.go:171","msg":"trace[30651447] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"276.430734ms","start":"2026-02-10T15:01:08.21155Z","end":"2026-02-10T15:01:08.487981Z","steps":["trace[30651447] 'process raft request' (duration: 276.124356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:08.488149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.040033ms","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-10T15:01:08.488165Z","caller":"traceutil/trace.go:171","msg":"trace[1585893348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"248.083025ms","start":"2026-02-10T15:01:08.240077Z","end":"2026-02-10T15:01:08.48816Z","steps":["trace[1585893348] 'agreement among raft nodes before linearized reading' (duration: 248.011193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:08.48826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.34535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:01:08.488272Z","caller":"traceutil/trace.go:171","msg":"trace[877102332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"238.393972ms","start":"2026-02-10T15:01:08.249874Z","end":"2026-02-10T15:01:08.488268Z","steps":["trace[877102332] 'agreement among raft nodes before linearized reading' (duration: 238.34336ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:09.069477Z","caller":"traceutil/trace.go:171","msg":"trace[1469903010] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"107.6432ms","start":"2026-02-10T15:01:08.961819Z","end":"2026-02-10T15:01:09.069462Z","steps":["trace[1469903010] 'process raft request' (duration: 107.554628ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:10.639127Z","caller":"traceutil/trace.go:171","msg":"trace[2020221411] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"108.047919ms","start":"2026-02-10T15:01:10.531052Z","end":"2026-02-10T15:01:10.6391Z","steps":["trace[2020221411] 'read index received' (duration: 107.909716ms)","trace[2020221411] 'applied index is now lower than readState.Index' (duration: 137.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:10.63935Z","caller":"traceutil/trace.go:171","msg":"trace[2066126149] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"145.854506ms","start":"2026-02-10T15:01:10.493487Z","end":"2026-02-10T15:01:10.639342Z","steps":["trace[2066126149] 'process raft request' (duration: 145.521838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:10.639553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.493692ms","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-10T15:01:10.639583Z","caller":"traceutil/trace.go:171","msg":"trace[1542958160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"108.569724ms","start":"2026-02-10T15:01:10.531005Z","end":"2026-02-10T15:01:10.639575Z","steps":["trace[1542958160] 'agreement among raft nodes before linearized reading' (duration: 108.476782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:10.639733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.590488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:01:10.639802Z","caller":"traceutil/trace.go:171","msg":"trace[483758729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"107.764243ms","start":"2026-02-10T15:01:10.532021Z","end":"2026-02-10T15:01:10.639785Z","steps":["trace[483758729] 'agreement among raft nodes before linearized reading' (duration: 107.525407ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:10.978316Z","caller":"traceutil/trace.go:171","msg":"trace[1095232271] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"331.297046ms","start":"2026-02-10T15:01:10.646248Z","end":"2026-02-10T15:01:10.977545Z","steps":["trace[1095232271] 'process raft request' (duration: 331.126122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:10.979404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:10.64623Z","time spent":"332.417625ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:11.028297Z","caller":"traceutil/trace.go:171","msg":"trace[284848604] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"378.886898ms","start":"2026-02-10T15:01:10.649389Z","end":"2026-02-10T15:01:11.028276Z","steps":["trace[284848604] 'process raft request' (duration: 378.462826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:11.029981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:10.64937Z","time spent":"380.544711ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:12.661486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.071341ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:01:12.662223Z","caller":"traceutil/trace.go:171","msg":"trace[499838804] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"129.222733ms","start":"2026-02-10T15:01:12.532984Z","end":"2026-02-10T15:01:12.662207Z","steps":["trace[499838804] 'process raft request' (duration: 128.571816ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:26.573016Z","caller":"traceutil/trace.go:171","msg":"trace[561508700] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"111.742144ms","start":"2026-02-10T15:01:26.461249Z","end":"2026-02-10T15:01:26.572992Z","steps":["trace[561508700] 'process raft request' (duration: 111.576399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:26.80638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.702368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:26.806446Z","caller":"traceutil/trace.go:171","msg":"trace[1614514236] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"277.580983ms","start":"2026-02-10T15:01:26.528853Z","end":"2026-02-10T15:01:26.806434Z","steps":["trace[1614514236] 'process raft request' (duration: 109.776802ms)","trace[1614514236] 'compare' (duration: 167.549825ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:26.867777Z","caller":"traceutil/trace.go:171","msg":"trace[1637886025] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1077; }","duration":"171.870146ms","start":"2026-02-10T15:01:26.695885Z","end":"2026-02-10T15:01:26.867756Z","steps":["trace[1637886025] 'read index received' (duration: 171.611898ms)","trace[1637886025] 'applied index is now lower than readState.Index' (duration: 257.398µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:26.868321Z","caller":"traceutil/trace.go:171","msg":"trace[1782561313] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"279.897642ms","start":"2026-02-10T15:01:26.588408Z","end":"2026-02-10T15:01:26.868306Z","steps":["trace[1782561313] 'process raft request' (duration: 279.300978ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:26.869543Z","caller":"traceutil/trace.go:171","msg":"trace[1672100325] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"285.101357ms","start":"2026-02-10T15:01:26.584411Z","end":"2026-02-10T15:01:26.869512Z","steps":["trace[1672100325] 'process raft request' (duration: 283.149276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:26.870476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.546164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-10T15:01:26.870527Z","caller":"traceutil/trace.go:171","msg":"trace[1146220447] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1037; }","duration":"174.668288ms","start":"2026-02-10T15:01:26.695846Z","end":"2026-02-10T15:01:26.870514Z","steps":["trace[1146220447] 'agreement among raft nodes before linearized reading' (duration: 174.488733ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:26.870976Z","caller":"traceutil/trace.go:171","msg":"trace[1394314155] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"286.333119ms","start":"2026-02-10T15:01:26.584628Z","end":"2026-02-10T15:01:26.870961Z","steps":["trace[1394314155] 'process raft request' (duration: 283.035315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:27.084058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.592993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:27.085364Z","caller":"traceutil/trace.go:171","msg":"trace[1470613238] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"202.343002ms","start":"2026-02-10T15:01:26.882997Z","end":"2026-02-10T15:01:27.08534Z","steps":["trace[1470613238] 'process raft request' (duration: 28.367583ms)","trace[1470613238] 'compare' (duration: 172.306226ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:27.086923Z","caller":"traceutil/trace.go:171","msg":"trace[756015487] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"200.567666ms","start":"2026-02-10T15:01:26.886335Z","end":"2026-02-10T15:01:27.086902Z","steps":["trace[756015487] 'process raft request' (duration: 197.833595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.249727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388279841756398,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:03:30.731309Z","caller":"traceutil/trace.go:171","msg":"trace[714347078] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1608; }","duration":"982.552351ms","start":"2026-02-10T15:03:29.748742Z","end":"2026-02-10T15:03:30.731295Z","steps":["trace[714347078] 'read index received' (duration: 982.433958ms)","trace[714347078] 'applied index is now lower than readState.Index' (duration: 117.813µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:30.731373Z","caller":"traceutil/trace.go:171","msg":"trace[1410466799] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"984.970013ms","start":"2026-02-10T15:03:29.746398Z","end":"2026-02-10T15:03:30.731368Z","steps":["trace[1410466799] 'process raft request' (duration: 984.817979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.74638Z","time spent":"985.007453ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:30.73154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.797417ms","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-10T15:03:30.731555Z","caller":"traceutil/trace.go:171","msg":"trace[742480793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1541; }","duration":"982.830377ms","start":"2026-02-10T15:03:29.74872Z","end":"2026-02-10T15:03:30.73155Z","steps":["trace[742480793] 'agreement among raft nodes before linearized reading' (duration: 982.773016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.748708Z","time spent":"982.856178ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T15:03:30.731623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.056301ms","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-10T15:03:30.731634Z","caller":"traceutil/trace.go:171","msg":"trace[648678382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1541; }","duration":"974.081561ms","start":"2026-02-10T15:03:29.757549Z","end":"2026-02-10T15:03:30.731631Z","steps":["trace[648678382] 'agreement among raft nodes before linearized reading' (duration: 974.051091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.757541Z","time spent":"974.101353ms","remote":"127.0.0.1:38876","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-10T15:03:30.731807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.0694ms","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-10T15:03:30.731837Z","caller":"traceutil/trace.go:171","msg":"trace[1645256420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1541; }","duration":"156.125671ms","start":"2026-02-10T15:03:30.575708Z","end":"2026-02-10T15:03:30.731833Z","steps":["trace[1645256420] 'agreement among raft nodes before linearized reading' (duration: 156.076099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.14731ms","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:532"} {"level":"info","ts":"2026-02-10T15:03:30.731907Z","caller":"traceutil/trace.go:171","msg":"trace[518310420] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1541; }","duration":"619.182841ms","start":"2026-02-10T15:03:30.112721Z","end":"2026-02-10T15:03:30.731904Z","steps":["trace[518310420] 'agreement among raft nodes before linearized reading' (duration: 619.15247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:30.112709Z","time spent":"619.205841ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-10T15:03:30.731971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.956184ms","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-10T15:03:30.731981Z","caller":"traceutil/trace.go:171","msg":"trace[338403178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1541; }","duration":"821.989574ms","start":"2026-02-10T15:03:29.909988Z","end":"2026-02-10T15:03:30.731978Z","steps":["trace[338403178] 'agreement among raft nodes before linearized reading' (duration: 821.957373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.731991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.909961Z","time spent":"822.028345ms","remote":"127.0.0.1:38876","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-10T15:03:30.732058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.395314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-10T15:03:30.732072Z","caller":"traceutil/trace.go:171","msg":"trace[31346483] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1541; }","duration":"881.449076ms","start":"2026-02-10T15:03:29.850617Z","end":"2026-02-10T15:03:30.732066Z","steps":["trace[31346483] 'agreement among raft nodes before linearized reading' (duration: 881.407225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.732083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.850596Z","time spent":"881.484116ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:03:30.732122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.850389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:30.73214Z","caller":"traceutil/trace.go:171","msg":"trace[552576722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1541; }","duration":"951.891801ms","start":"2026-02-10T15:03:29.780244Z","end":"2026-02-10T15:03:30.732136Z","steps":["trace[552576722] 'agreement among raft nodes before linearized reading' (duration: 951.87066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.73215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.780227Z","time spent":"951.920851ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:03:35.015145Z","caller":"traceutil/trace.go:171","msg":"trace[935075730] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"125.737269ms","start":"2026-02-10T15:03:34.889307Z","end":"2026-02-10T15:03:35.015044Z","steps":["trace[935075730] 'process raft request' (duration: 118.4121ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:35.017169Z","caller":"traceutil/trace.go:171","msg":"trace[330066042] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"126.900889ms","start":"2026-02-10T15:03:34.889364Z","end":"2026-02-10T15:03:35.016265Z","steps":["trace[330066042] 'process raft request' (duration: 123.950833ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:45.456943Z","caller":"traceutil/trace.go:171","msg":"trace[1115849615] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"130.017506ms","start":"2026-02-10T15:04:45.326902Z","end":"2026-02-10T15:04:45.45692Z","steps":["trace[1115849615] 'process raft request' (duration: 129.888913ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:09.407544Z","caller":"traceutil/trace.go:171","msg":"trace[1638612113] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"135.53206ms","start":"2026-02-10T15:05:09.27199Z","end":"2026-02-10T15:05:09.407522Z","steps":["trace[1638612113] 'read index received' (duration: 135.352466ms)","trace[1638612113] 'applied index is now lower than readState.Index' (duration: 178.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:09.407649Z","caller":"traceutil/trace.go:171","msg":"trace[1110339450] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"140.654042ms","start":"2026-02-10T15:05:09.266981Z","end":"2026-02-10T15:05:09.407635Z","steps":["trace[1110339450] 'process raft request' (duration: 140.422876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:09.407814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.259445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"info","ts":"2026-02-10T15:05:09.407882Z","caller":"traceutil/trace.go:171","msg":"trace[474001254] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1981; }","duration":"132.343707ms","start":"2026-02-10T15:05:09.275522Z","end":"2026-02-10T15:05:09.407866Z","steps":["trace[474001254] 'agreement among raft nodes before linearized reading' (duration: 132.225694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:09.408135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.123256ms","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-10T15:05:09.408172Z","caller":"traceutil/trace.go:171","msg":"trace[1403732745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1981; }","duration":"136.181107ms","start":"2026-02-10T15:05:09.271981Z","end":"2026-02-10T15:05:09.408162Z","steps":["trace[1403732745] 'agreement among raft nodes before linearized reading' (duration: 136.058614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:09.408223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.149508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-10T15:05:09.408256Z","caller":"traceutil/trace.go:171","msg":"trace[364801827] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1981; }","duration":"116.207469ms","start":"2026-02-10T15:05:09.292039Z","end":"2026-02-10T15:05:09.408247Z","steps":["trace[364801827] 'agreement among raft nodes before linearized reading' (duration: 116.153968ms)"],"step_count":1}