{"level":"warn","ts":"2026-04-17T00:29:21.311412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:29:21.311539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-04-17T00:29:21.311626Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:29:21.311637Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-17T00:29:21.311666Z","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-04-17T00:29:21.312499Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-17T00:29:21.312596Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-04-17T00:29:21.317562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.689448ms"} {"level":"info","ts":"2026-04-17T00:29:21.327193Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-17T00:29:21.327272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:29:21.327302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-17T00:29:21.327313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:29:21.327334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-17T00:29:21.327377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-17T00:29:21.338171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:29:21.340264Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:29:21.343015Z","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-04-17T00:29:21.345787Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:29:21.346142Z","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-04-17T00:29:21.346233Z","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-04-17T00:29:21.346211Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:29:21.346268Z","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-04-17T00:29:21.347175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-17T00:29:21.347308Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-17T00:29:21.351736Z","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-04-17T00:29:21.351835Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-17T00:29:21.351883Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-17T00:29:21.35221Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:29:21.352269Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:29:21.528805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:29:21.528872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:29:21.528909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-17T00:29:21.528931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:29:21.52894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-17T00:29:21.528959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-17T00:29:21.528973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-17T00:29:21.628228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:29:21.628246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:29:21.628311Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:21.628669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:29:21.62903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:29:21.629108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:29:21.632628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:29:21.636594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-17T00:29:21.725305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:21.73005Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:29:21.733099Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-17T00:29:23.643698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328117,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:29:23.660521Z","caller":"traceutil/trace.go:171","msg":"trace[1336583320] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"518.102347ms","start":"2026-04-17T00:29:23.142389Z","end":"2026-04-17T00:29:23.660491Z","steps":["trace[1336583320] 'process raft request' (duration: 517.84916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:23.661166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.14238Z","time spent":"518.22024ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:29:26.061211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.400943087s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:29:26.08111Z","caller":"traceutil/trace.go:171","msg":"trace[1894412882] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.859465319s","start":"2026-04-17T00:29:23.221594Z","end":"2026-04-17T00:29:26.08106Z","steps":["trace[1894412882] 'process raft request' (duration: 2.839835587s)","trace[1894412882] 'compare' (duration: 19.136829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:26.081247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.221574Z","time spent":"2.859607253s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:26.081224Z","caller":"traceutil/trace.go:171","msg":"trace[2086553836] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.85877791s","start":"2026-04-17T00:29:23.222408Z","end":"2026-04-17T00:29:26.081186Z","steps":["trace[2086553836] 'process raft request' (duration: 2.858528044s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.081464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.222404Z","time spent":"2.859038858s","remote":"127.0.0.1:39278","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-04-17T00:29:26.081442Z","caller":"traceutil/trace.go:171","msg":"trace[669301179] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"2.859227593s","start":"2026-04-17T00:29:23.222169Z","end":"2026-04-17T00:29:26.081397Z","steps":["trace[669301179] 'process raft request' (duration: 2.858618237s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:26.08163Z","caller":"traceutil/trace.go:171","msg":"trace[136290760] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.859288704s","start":"2026-04-17T00:29:23.222324Z","end":"2026-04-17T00:29:26.081612Z","steps":["trace[136290760] 'process raft request' (duration: 2.858525535s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.081713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.22215Z","time spent":"2.859445888s","remote":"127.0.0.1:39218","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-04-17T00:29:26.081816Z","caller":"traceutil/trace.go:171","msg":"trace[1786013965] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.849739777s","start":"2026-04-17T00:29:23.232064Z","end":"2026-04-17T00:29:26.081804Z","steps":["trace[1786013965] 'process raft request' (duration: 2.848951237s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.081807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.222315Z","time spent":"2.859396617s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:26.081871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.232057Z","time spent":"2.849793408s","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:26.082029Z","caller":"traceutil/trace.go:171","msg":"trace[246678789] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.857117185s","start":"2026-04-17T00:29:23.2249Z","end":"2026-04-17T00:29:26.082017Z","steps":["trace[246678789] 'process raft request' (duration: 2.856074598s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.082075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.224877Z","time spent":"2.857178887s","remote":"127.0.0.1:39278","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-04-17T00:29:26.082154Z","caller":"traceutil/trace.go:171","msg":"trace[1095103616] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.859765536s","start":"2026-04-17T00:29:23.222375Z","end":"2026-04-17T00:29:26.082141Z","steps":["trace[1095103616] 'process raft request' (duration: 2.858520764s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.082248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.22237Z","time spent":"2.859847568s","remote":"127.0.0.1:39278","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-04-17T00:29:26.082359Z","caller":"traceutil/trace.go:171","msg":"trace[1475379599] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.845942405s","start":"2026-04-17T00:29:23.236407Z","end":"2026-04-17T00:29:26.082349Z","steps":["trace[1475379599] 'process raft request' (duration: 2.844654482s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.082413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.236401Z","time spent":"2.845988936s","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:26.082872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.939729883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-17T00:29:26.082932Z","caller":"traceutil/trace.go:171","msg":"trace[757100525] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"2.939816845s","start":"2026-04-17T00:29:23.143102Z","end":"2026-04-17T00:29:26.082919Z","steps":["trace[757100525] 'agreement among raft nodes before linearized reading' (duration: 2.93961175s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.082969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.143097Z","time spent":"2.939863986s","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-17T00:29:26.088268Z","caller":"traceutil/trace.go:171","msg":"trace[1662832962] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"2.938030999s","start":"2026-04-17T00:29:23.143114Z","end":"2026-04-17T00:29:26.081145Z","steps":["trace[1662832962] 'read index received' (duration: 517.196003ms)","trace[1662832962] 'applied index is now lower than readState.Index' (duration: 2.420833726s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:26.589286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:29:26.62524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999621757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T00:29:26.625301Z","caller":"traceutil/trace.go:171","msg":"trace[1522259927] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000041218s","start":"2026-04-17T00:29:24.625243Z","end":"2026-04-17T00:29:26.625285Z","steps":["trace[1522259927] 'agreement among raft nodes before linearized reading' (duration: 1.999963356s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:26.625358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:24.625224Z","time spent":"2.00012491s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:29:26 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:29:27.090116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:29:27.590918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:29:27.781555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.693282652s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:29:27.857103Z","caller":"traceutil/trace.go:171","msg":"trace[366818034] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:13; }","duration":"1.76862895s","start":"2026-04-17T00:29:26.088419Z","end":"2026-04-17T00:29:27.857048Z","steps":["trace[366818034] 'read index received' (duration: 1.693392434s)","trace[366818034] 'applied index is now lower than readState.Index' (duration: 75.233826ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:27.857228Z","caller":"traceutil/trace.go:171","msg":"trace[1227837998] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.769075312s","start":"2026-04-17T00:29:26.088141Z","end":"2026-04-17T00:29:27.857216Z","steps":["trace[1227837998] 'process raft request' (duration: 1.693722064s)","trace[1227837998] 'compare' (duration: 74.527526ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:27.857296Z","caller":"traceutil/trace.go:171","msg":"trace[680384644] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.739009326s","start":"2026-04-17T00:29:26.118259Z","end":"2026-04-17T00:29:27.857269Z","steps":["trace[680384644] 'process raft request' (duration: 1.738739289s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.088127Z","time spent":"1.769143603s","remote":"127.0.0.1:39278","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-04-17T00:29:27.857457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.118231Z","time spent":"1.73913595s","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:29:27.857606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.637425864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:27.857649Z","caller":"traceutil/trace.go:171","msg":"trace[1126764516] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"4.637491485s","start":"2026-04-17T00:29:23.220147Z","end":"2026-04-17T00:29:27.857639Z","steps":["trace[1126764516] 'agreement among raft nodes before linearized reading' (duration: 4.637420263s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.85768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.220136Z","time spent":"4.637537937s","remote":"127.0.0.1:38824","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-04-17T00:29:27.857595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.634640308s","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":"warn","ts":"2026-04-17T00:29:27.857696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.712994592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:27.857739Z","caller":"traceutil/trace.go:171","msg":"trace[2108173723] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:18; }","duration":"4.713056364s","start":"2026-04-17T00:29:23.144669Z","end":"2026-04-17T00:29:27.857726Z","steps":["trace[2108173723] 'agreement among raft nodes before linearized reading' (duration: 4.712989352s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:27.857761Z","caller":"traceutil/trace.go:171","msg":"trace[894168879] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.769097142s","start":"2026-04-17T00:29:26.088652Z","end":"2026-04-17T00:29:27.857749Z","steps":["trace[894168879] 'process raft request' (duration: 1.768149717s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.455465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:27.857784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.144659Z","time spent":"4.713115245s","remote":"127.0.0.1:38760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-17T00:29:27.857764Z","caller":"traceutil/trace.go:171","msg":"trace[1435895535] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"4.634852754s","start":"2026-04-17T00:29:23.222866Z","end":"2026-04-17T00:29:27.857719Z","steps":["trace[1435895535] 'agreement among raft nodes before linearized reading' (duration: 4.634416413s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:27.85781Z","caller":"traceutil/trace.go:171","msg":"trace[644574525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"732.511528ms","start":"2026-04-17T00:29:27.12529Z","end":"2026-04-17T00:29:27.857802Z","steps":["trace[644574525] 'agreement among raft nodes before linearized reading' (duration: 732.474097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.222856Z","time spent":"4.634963437s","remote":"127.0.0.1:38792","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":"info","ts":"2026-04-17T00:29:27.857897Z","caller":"traceutil/trace.go:171","msg":"trace[669446684] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.769210195s","start":"2026-04-17T00:29:26.088678Z","end":"2026-04-17T00:29:27.857888Z","steps":["trace[669446684] 'process raft request' (duration: 1.768194439s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.639051536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:27.85795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.088649Z","time spent":"1.769281836s","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:27.857964Z","caller":"traceutil/trace.go:171","msg":"trace[1649579183] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:0; response_revision:18; }","duration":"4.639111918s","start":"2026-04-17T00:29:23.218843Z","end":"2026-04-17T00:29:27.857955Z","steps":["trace[1649579183] 'agreement among raft nodes before linearized reading' (duration: 4.639059117s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.660008009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:27.858031Z","caller":"traceutil/trace.go:171","msg":"trace[1900379148] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"4.66004469s","start":"2026-04-17T00:29:23.197978Z","end":"2026-04-17T00:29:27.858023Z","steps":["trace[1900379148] 'agreement among raft nodes before linearized reading' (duration: 4.659993038s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.858064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.197881Z","time spent":"4.660176493s","remote":"127.0.0.1:38824","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-04-17T00:29:27.858059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770209631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-k72m2\" ","response":"range_response_count:1 size:857"} {"level":"warn","ts":"2026-04-17T00:29:27.857994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.218823Z","time spent":"4.639165819s","remote":"127.0.0.1:38740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"info","ts":"2026-04-17T00:29:27.858105Z","caller":"traceutil/trace.go:171","msg":"trace[201078780] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-k72m2; range_end:; response_count:1; response_revision:18; }","duration":"1.770318793s","start":"2026-04-17T00:29:26.087775Z","end":"2026-04-17T00:29:27.858093Z","steps":["trace[201078780] 'agreement among raft nodes before linearized reading' (duration: 1.770213761s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:27.858145Z","caller":"traceutil/trace.go:171","msg":"trace[653096043] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.769358038s","start":"2026-04-17T00:29:26.088777Z","end":"2026-04-17T00:29:27.858135Z","steps":["trace[653096043] 'process raft request' (duration: 1.768126237s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.858163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.445557074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:27.858187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.087753Z","time spent":"1.770423567s","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":880,"request content":"key:\"/registry/certificatesigningrequests/csr-k72m2\" "} {"level":"info","ts":"2026-04-17T00:29:27.858198Z","caller":"traceutil/trace.go:171","msg":"trace[886555563] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:18; }","duration":"2.445621866s","start":"2026-04-17T00:29:25.412567Z","end":"2026-04-17T00:29:27.858189Z","steps":["trace[886555563] 'agreement among raft nodes before linearized reading' (duration: 2.445581275s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.858198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.088768Z","time spent":"1.769404729s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:27.857813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.08863Z","time spent":"1.769159384s","remote":"127.0.0.1:39218","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-04-17T00:29:27.858226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:25.412543Z","time spent":"2.445676728s","remote":"127.0.0.1:38824","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-04-17T00:29:27.858306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.73757325s","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-04-17T00:29:27.858336Z","caller":"traceutil/trace.go:171","msg":"trace[125489904] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"3.73763092s","start":"2026-04-17T00:29:24.120697Z","end":"2026-04-17T00:29:27.858328Z","steps":["trace[125489904] 'agreement among raft nodes before linearized reading' (duration: 3.737591099s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:27.858339Z","caller":"traceutil/trace.go:171","msg":"trace[1332406915] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.76948777s","start":"2026-04-17T00:29:26.088843Z","end":"2026-04-17T00:29:27.858331Z","steps":["trace[1332406915] 'process raft request' (duration: 1.768109765s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.858361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:24.120674Z","time spent":"3.737682362s","remote":"127.0.0.1:39106","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-04-17T00:29:27.85838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.088811Z","time spent":"1.769550332s","remote":"127.0.0.1:39278","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-04-17T00:29:27.85831Z","caller":"traceutil/trace.go:171","msg":"trace[116433842] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.769510523s","start":"2026-04-17T00:29:26.08879Z","end":"2026-04-17T00:29:27.8583Z","steps":["trace[116433842] 'process raft request' (duration: 1.768142148s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.858478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.584926702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:27.858484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.088783Z","time spent":"1.769655586s","remote":"127.0.0.1:39278","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-04-17T00:29:27.858556Z","caller":"traceutil/trace.go:171","msg":"trace[1769528131] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"4.584998804s","start":"2026-04-17T00:29:23.273514Z","end":"2026-04-17T00:29:27.858512Z","steps":["trace[1769528131] 'agreement among raft nodes before linearized reading' (duration: 4.584898212s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.8586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:23.273482Z","time spent":"4.585107146s","remote":"127.0.0.1:38824","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":"info","ts":"2026-04-17T00:29:27.85849Z","caller":"traceutil/trace.go:171","msg":"trace[497186982] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.744608932s","start":"2026-04-17T00:29:26.113873Z","end":"2026-04-17T00:29:27.858482Z","steps":["trace[497186982] 'process raft request' (duration: 1.743101744s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.857843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:27.125027Z","time spent":"732.810796ms","remote":"127.0.0.1:38718","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-04-17T00:29:27.858728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:26.113862Z","time spent":"1.744838258s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:27.858129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.738818703s","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-04-17T00:29:27.858944Z","caller":"traceutil/trace.go:171","msg":"trace[92322021] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"3.739670185s","start":"2026-04-17T00:29:24.119261Z","end":"2026-04-17T00:29:27.858931Z","steps":["trace[92322021] 'agreement among raft nodes before linearized reading' (duration: 3.738845434s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:27.859015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:24.11923Z","time spent":"3.739770158s","remote":"127.0.0.1:39090","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":"info","ts":"2026-04-17T00:29:28.054486Z","caller":"traceutil/trace.go:171","msg":"trace[2013436143] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"153.192899ms","start":"2026-04-17T00:29:27.901266Z","end":"2026-04-17T00:29:28.054459Z","steps":["trace[2013436143] 'process raft request' (duration: 88.139919ms)","trace[2013436143] 'compare' (duration: 64.712002ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:28.054851Z","caller":"traceutil/trace.go:171","msg":"trace[1635828365] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"152.179782ms","start":"2026-04-17T00:29:27.902664Z","end":"2026-04-17T00:29:28.054844Z","steps":["trace[1635828365] 'process raft request' (duration: 151.930766ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.055002Z","caller":"traceutil/trace.go:171","msg":"trace[103694055] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"153.66602ms","start":"2026-04-17T00:29:27.901329Z","end":"2026-04-17T00:29:28.054995Z","steps":["trace[103694055] 'process raft request' (duration: 153.062474ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.055039Z","caller":"traceutil/trace.go:171","msg":"trace[1003966125] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"152.271385ms","start":"2026-04-17T00:29:27.902758Z","end":"2026-04-17T00:29:28.05503Z","steps":["trace[1003966125] 'process raft request' (duration: 151.898485ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.054954Z","caller":"traceutil/trace.go:171","msg":"trace[454053061] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"131.645161ms","start":"2026-04-17T00:29:27.923289Z","end":"2026-04-17T00:29:28.054934Z","steps":["trace[454053061] 'process raft request' (duration: 131.567059ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.055342Z","caller":"traceutil/trace.go:171","msg":"trace[1416512011] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:31; }","duration":"153.937486ms","start":"2026-04-17T00:29:27.901395Z","end":"2026-04-17T00:29:28.055333Z","steps":["trace[1416512011] 'read index received' (duration: 15.934771ms)","trace[1416512011] 'applied index is now lower than readState.Index' (duration: 138.001805ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:28.055417Z","caller":"traceutil/trace.go:171","msg":"trace[1439368375] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"152.587143ms","start":"2026-04-17T00:29:27.902824Z","end":"2026-04-17T00:29:28.055411Z","steps":["trace[1439368375] 'process raft request' (duration: 151.884844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.05555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.146083ms","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-04-17T00:29:28.055584Z","caller":"traceutil/trace.go:171","msg":"trace[410496112] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:35; }","duration":"154.199704ms","start":"2026-04-17T00:29:27.901372Z","end":"2026-04-17T00:29:28.055572Z","steps":["trace[410496112] 'agreement among raft nodes before linearized reading' (duration: 154.098862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.055668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.291981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:28.055692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.042398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:29:28.055756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.85656ms","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-04-17T00:29:28.055758Z","caller":"traceutil/trace.go:171","msg":"trace[760827417] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"153.405944ms","start":"2026-04-17T00:29:27.902317Z","end":"2026-04-17T00:29:28.055723Z","steps":["trace[760827417] 'agreement among raft nodes before linearized reading' (duration: 153.180098ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.055774Z","caller":"traceutil/trace.go:171","msg":"trace[866860029] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"138.14329ms","start":"2026-04-17T00:29:27.917613Z","end":"2026-04-17T00:29:28.055756Z","steps":["trace[866860029] 'agreement among raft nodes before linearized reading' (duration: 137.988306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.05579Z","caller":"traceutil/trace.go:171","msg":"trace[870505396] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:35; }","duration":"152.912032ms","start":"2026-04-17T00:29:27.902868Z","end":"2026-04-17T00:29:28.05578Z","steps":["trace[870505396] 'agreement among raft nodes before linearized reading' (duration: 152.86457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.055683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.732587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:28.055873Z","caller":"traceutil/trace.go:171","msg":"trace[1802128597] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"135.567834ms","start":"2026-04-17T00:29:27.920295Z","end":"2026-04-17T00:29:28.055863Z","steps":["trace[1802128597] 'process raft request' (duration: 134.480505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.055946Z","caller":"traceutil/trace.go:171","msg":"trace[923814782] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:35; }","duration":"152.988423ms","start":"2026-04-17T00:29:27.902895Z","end":"2026-04-17T00:29:28.055883Z","steps":["trace[923814782] 'agreement among raft nodes before linearized reading' (duration: 152.629504ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.271756Z","caller":"traceutil/trace.go:171","msg":"trace[1447072091] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"148.909984ms","start":"2026-04-17T00:29:28.122793Z","end":"2026-04-17T00:29:28.271703Z","steps":["trace[1447072091] 'process raft request' (duration: 124.283552ms)","trace[1447072091] 'compare' (duration: 24.221891ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:28.271787Z","caller":"traceutil/trace.go:171","msg":"trace[1230617847] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:44; }","duration":"146.345329ms","start":"2026-04-17T00:29:28.125405Z","end":"2026-04-17T00:29:28.27175Z","steps":["trace[1230617847] 'read index received' (duration: 121.678157ms)","trace[1230617847] 'applied index is now lower than readState.Index' (duration: 24.663802ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:28.272171Z","caller":"traceutil/trace.go:171","msg":"trace[1083830628] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"111.910586ms","start":"2026-04-17T00:29:28.160245Z","end":"2026-04-17T00:29:28.272156Z","steps":["trace[1083830628] 'process raft request' (duration: 111.230099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.272455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.000975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:28.27254Z","caller":"traceutil/trace.go:171","msg":"trace[1869411733] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:42; }","duration":"147.150049ms","start":"2026-04-17T00:29:28.125376Z","end":"2026-04-17T00:29:28.272526Z","steps":["trace[1869411733] 'agreement among raft nodes before linearized reading' (duration: 146.908933ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:28.272781Z","caller":"traceutil/trace.go:171","msg":"trace[1811105630] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"111.084005ms","start":"2026-04-17T00:29:28.161605Z","end":"2026-04-17T00:29:28.272689Z","steps":["trace[1811105630] 'process raft request' (duration: 109.985908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.272875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.976504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:28.273016Z","caller":"traceutil/trace.go:171","msg":"trace[1141623914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"147.177478ms","start":"2026-04-17T00:29:28.125751Z","end":"2026-04-17T00:29:28.272929Z","steps":["trace[1141623914] 'agreement among raft nodes before linearized reading' (duration: 146.727117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:28.998192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.462998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:29:28.998395Z","caller":"traceutil/trace.go:171","msg":"trace[835256090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:90; }","duration":"124.724095ms","start":"2026-04-17T00:29:28.873645Z","end":"2026-04-17T00:29:28.99837Z","steps":["trace[835256090] 'agreement among raft nodes before linearized reading' (duration: 124.446128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:29.640849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.883215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:29.64102Z","caller":"traceutil/trace.go:171","msg":"trace[124555750] linearizableReadLoop","detail":"{readStateIndex:135; appliedIndex:134; }","duration":"230.595121ms","start":"2026-04-17T00:29:29.410399Z","end":"2026-04-17T00:29:29.640994Z","steps":["trace[124555750] 'read index received' (duration: 7.171672ms)","trace[124555750] 'applied index is now lower than readState.Index' (duration: 223.421719ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:29.641065Z","caller":"traceutil/trace.go:171","msg":"trace[1457039690] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"231.042163ms","start":"2026-04-17T00:29:29.409976Z","end":"2026-04-17T00:29:29.641018Z","steps":["trace[1457039690] 'compare' (duration: 222.614597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:29.641282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.869629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:29.641343Z","caller":"traceutil/trace.go:171","msg":"trace[1586586330] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:130; }","duration":"230.988501ms","start":"2026-04-17T00:29:29.410334Z","end":"2026-04-17T00:29:29.641323Z","steps":["trace[1586586330] 'agreement among raft nodes before linearized reading' (duration: 230.768786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:29.916824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.023565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:29.916953Z","caller":"traceutil/trace.go:171","msg":"trace[1922292617] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"210.175673ms","start":"2026-04-17T00:29:29.706757Z","end":"2026-04-17T00:29:29.916933Z","steps":["trace[1922292617] 'process raft request' (duration: 37.971665ms)","trace[1922292617] 'compare' (duration: 171.845109ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:29.922625Z","caller":"traceutil/trace.go:171","msg":"trace[1610462953] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"215.494258ms","start":"2026-04-17T00:29:29.707089Z","end":"2026-04-17T00:29:29.922583Z","steps":["trace[1610462953] 'process raft request' (duration: 215.343624ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:30.103507Z","caller":"traceutil/trace.go:171","msg":"trace[697772109] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"122.411104ms","start":"2026-04-17T00:29:29.981069Z","end":"2026-04-17T00:29:30.10348Z","steps":["trace[697772109] 'process raft request' (duration: 44.423143ms)","trace[697772109] 'compare' (duration: 77.843897ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:30.125843Z","caller":"traceutil/trace.go:171","msg":"trace[2054943016] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"144.044257ms","start":"2026-04-17T00:29:29.981784Z","end":"2026-04-17T00:29:30.125828Z","steps":["trace[2054943016] 'process raft request' (duration: 143.975855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:30.364603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.89159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:30.364811Z","caller":"traceutil/trace.go:171","msg":"trace[662379146] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"157.739665ms","start":"2026-04-17T00:29:30.207014Z","end":"2026-04-17T00:29:30.364753Z","steps":["trace[662379146] 'process raft request' (duration: 157.682754ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:30.364915Z","caller":"traceutil/trace.go:171","msg":"trace[704756952] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"160.142175ms","start":"2026-04-17T00:29:30.204662Z","end":"2026-04-17T00:29:30.364804Z","steps":["trace[704756952] 'compare' (duration: 157.752296ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:30.550098Z","caller":"traceutil/trace.go:171","msg":"trace[203133730] linearizableReadLoop","detail":"{readStateIndex:151; appliedIndex:150; }","duration":"181.829341ms","start":"2026-04-17T00:29:30.368245Z","end":"2026-04-17T00:29:30.550074Z","steps":["trace[203133730] 'read index received' (duration: 139.955678ms)","trace[203133730] 'applied index is now lower than readState.Index' (duration: 41.872423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:30.550276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.008615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:30.550339Z","caller":"traceutil/trace.go:171","msg":"trace[766727883] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:146; }","duration":"182.104048ms","start":"2026-04-17T00:29:30.368198Z","end":"2026-04-17T00:29:30.550302Z","steps":["trace[766727883] 'agreement among raft nodes before linearized reading' (duration: 182.004745ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:30.550961Z","caller":"traceutil/trace.go:171","msg":"trace[1527366366] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"182.166719ms","start":"2026-04-17T00:29:30.368074Z","end":"2026-04-17T00:29:30.550241Z","steps":["trace[1527366366] 'process raft request' (duration: 140.201084ms)","trace[1527366366] 'compare' (duration: 41.650467ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:30.834255Z","caller":"traceutil/trace.go:171","msg":"trace[806368661] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"207.417474ms","start":"2026-04-17T00:29:30.626789Z","end":"2026-04-17T00:29:30.834206Z","steps":["trace[806368661] 'process raft request' (duration: 107.318398ms)","trace[806368661] 'compare' (duration: 99.84389ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:30.834288Z","caller":"traceutil/trace.go:171","msg":"trace[923791185] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"206.307406ms","start":"2026-04-17T00:29:30.627964Z","end":"2026-04-17T00:29:30.834271Z","steps":["trace[923791185] 'process raft request' (duration: 206.163702ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:30.83493Z","caller":"traceutil/trace.go:171","msg":"trace[440117444] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"205.112377ms","start":"2026-04-17T00:29:30.62979Z","end":"2026-04-17T00:29:30.834902Z","steps":["trace[440117444] 'process raft request' (duration: 204.414149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:31.304315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.665019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:31.304421Z","caller":"traceutil/trace.go:171","msg":"trace[640586884] linearizableReadLoop","detail":"{readStateIndex:160; appliedIndex:159; }","duration":"408.614835ms","start":"2026-04-17T00:29:30.895789Z","end":"2026-04-17T00:29:31.304404Z","steps":["trace[640586884] 'read index received' (duration: 205.788723ms)","trace[640586884] 'applied index is now lower than readState.Index' (duration: 202.825042ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:31.304473Z","caller":"traceutil/trace.go:171","msg":"trace[1613127307] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"408.83239ms","start":"2026-04-17T00:29:30.895602Z","end":"2026-04-17T00:29:31.304434Z","steps":["trace[1613127307] 'process raft request' (duration: 205.966956ms)","trace[1613127307] 'compare' (duration: 202.511445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:31.30452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.727568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:29:31.304648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:30.89558Z","time spent":"408.977724ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:31.304659Z","caller":"traceutil/trace.go:171","msg":"trace[500173720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:155; }","duration":"408.873091ms","start":"2026-04-17T00:29:30.895764Z","end":"2026-04-17T00:29:31.304637Z","steps":["trace[500173720] 'agreement among raft nodes before linearized reading' (duration: 408.726687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:31.304717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:30.895754Z","time spent":"408.950033ms","remote":"127.0.0.1:38720","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-04-17T00:29:31.304722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.446589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:31.304887Z","caller":"traceutil/trace.go:171","msg":"trace[821820373] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:0; response_revision:155; }","duration":"408.628744ms","start":"2026-04-17T00:29:30.896189Z","end":"2026-04-17T00:29:31.304818Z","steps":["trace[821820373] 'agreement among raft nodes before linearized reading' (duration: 408.390518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:31.304998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:30.896172Z","time spent":"408.811309ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-04-17T00:29:31.682913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.135855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:31.683019Z","caller":"traceutil/trace.go:171","msg":"trace[709004565] linearizableReadLoop","detail":"{readStateIndex:162; appliedIndex:161; }","duration":"328.454832ms","start":"2026-04-17T00:29:31.354547Z","end":"2026-04-17T00:29:31.683002Z","steps":["trace[709004565] 'read index received' (duration: 175.053051ms)","trace[709004565] 'applied index is now lower than readState.Index' (duration: 153.400571ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:31.683103Z","caller":"traceutil/trace.go:171","msg":"trace[1100250506] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"353.689541ms","start":"2026-04-17T00:29:31.329402Z","end":"2026-04-17T00:29:31.683092Z","steps":["trace[1100250506] 'process raft request' (duration: 200.304501ms)","trace[1100250506] 'compare' (duration: 152.98545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:31.683164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:31.329377Z","time spent":"353.763342ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:31.683249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.683199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:29:31.683281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.816908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:31.683349Z","caller":"traceutil/trace.go:171","msg":"trace[1961048732] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:0; response_revision:157; }","duration":"328.813722ms","start":"2026-04-17T00:29:31.354498Z","end":"2026-04-17T00:29:31.683311Z","steps":["trace[1961048732] 'agreement among raft nodes before linearized reading' (duration: 328.630396ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:31.683384Z","caller":"traceutil/trace.go:171","msg":"trace[1174003404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:157; }","duration":"309.976383ms","start":"2026-04-17T00:29:31.37339Z","end":"2026-04-17T00:29:31.683366Z","steps":["trace[1174003404] 'agreement among raft nodes before linearized reading' (duration: 309.730086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:31.683474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:31.373368Z","time spent":"310.085925ms","remote":"127.0.0.1:38718","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-04-17T00:29:31.683396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:31.354483Z","time spent":"328.903244ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:deployment-controller\" "} {"level":"warn","ts":"2026-04-17T00:29:31.991442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.847226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:31.991614Z","caller":"traceutil/trace.go:171","msg":"trace[1583539968] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"210.070633ms","start":"2026-04-17T00:29:31.781497Z","end":"2026-04-17T00:29:31.991568Z","steps":["trace[1583539968] 'process raft request' (duration: 98.027542ms)","trace[1583539968] 'compare' (duration: 111.695962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:31.991712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.252485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-17T00:29:31.991629Z","caller":"traceutil/trace.go:171","msg":"trace[1123092113] linearizableReadLoop","detail":"{readStateIndex:165; appliedIndex:164; }","duration":"183.086002ms","start":"2026-04-17T00:29:31.808456Z","end":"2026-04-17T00:29:31.991542Z","steps":["trace[1123092113] 'read index received' (duration: 70.982229ms)","trace[1123092113] 'applied index is now lower than readState.Index' (duration: 112.102503ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:31.991753Z","caller":"traceutil/trace.go:171","msg":"trace[182609818] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:160; }","duration":"183.315798ms","start":"2026-04-17T00:29:31.808425Z","end":"2026-04-17T00:29:31.991741Z","steps":["trace[182609818] 'agreement among raft nodes before linearized reading' (duration: 183.207605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:32.287325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.846598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:32.287396Z","caller":"traceutil/trace.go:171","msg":"trace[336491772] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"212.873375ms","start":"2026-04-17T00:29:32.07451Z","end":"2026-04-17T00:29:32.287384Z","steps":["trace[336491772] 'process raft request' (duration: 60.922584ms)","trace[336491772] 'compare' (duration: 151.773086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:32.87416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328384,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:29:32.911355Z","caller":"traceutil/trace.go:171","msg":"trace[1997174032] linearizableReadLoop","detail":"{readStateIndex:172; appliedIndex:171; }","duration":"537.073017ms","start":"2026-04-17T00:29:32.374105Z","end":"2026-04-17T00:29:32.911178Z","steps":["trace[1997174032] 'read index received' (duration: 535.735034ms)","trace[1997174032] 'applied index is now lower than readState.Index' (duration: 1.335393ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:32.911917Z","caller":"traceutil/trace.go:171","msg":"trace[67035447] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"547.794321ms","start":"2026-04-17T00:29:32.364092Z","end":"2026-04-17T00:29:32.911886Z","steps":["trace[67035447] 'process raft request' (duration: 545.861673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:32.912153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:32.364077Z","time spent":"547.886313ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:32.913074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.959594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:32.913223Z","caller":"traceutil/trace.go:171","msg":"trace[776033309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:167; }","duration":"539.142609ms","start":"2026-04-17T00:29:32.374062Z","end":"2026-04-17T00:29:32.913204Z","steps":["trace[776033309] 'agreement among raft nodes before linearized reading' (duration: 538.43561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:32.913453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:32.374046Z","time spent":"539.307722ms","remote":"127.0.0.1:38718","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-04-17T00:29:32.913811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.077719ms","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-04-17T00:29:32.91387Z","caller":"traceutil/trace.go:171","msg":"trace[1342899959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:167; }","duration":"277.167051ms","start":"2026-04-17T00:29:32.636679Z","end":"2026-04-17T00:29:32.913846Z","steps":["trace[1342899959] 'agreement among raft nodes before linearized reading' (duration: 277.053158ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:33.449731Z","caller":"traceutil/trace.go:171","msg":"trace[685882272] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"174.779645ms","start":"2026-04-17T00:29:33.274914Z","end":"2026-04-17T00:29:33.449693Z","steps":["trace[685882272] 'process raft request' (duration: 174.698923ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:33.449815Z","caller":"traceutil/trace.go:171","msg":"trace[750917583] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"175.008761ms","start":"2026-04-17T00:29:33.274751Z","end":"2026-04-17T00:29:33.44976Z","steps":["trace[750917583] 'process raft request' (duration: 100.447647ms)","trace[750917583] 'compare' (duration: 73.97775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:33.911778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.308682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:33.9119Z","caller":"traceutil/trace.go:171","msg":"trace[1708839634] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"399.107172ms","start":"2026-04-17T00:29:33.51277Z","end":"2026-04-17T00:29:33.911877Z","steps":["trace[1708839634] 'process raft request' (duration: 258.618636ms)","trace[1708839634] 'compare' (duration: 140.143928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:33.911957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:33.512754Z","time spent":"399.180604ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:34.076706Z","caller":"traceutil/trace.go:171","msg":"trace[1533125569] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"120.37147ms","start":"2026-04-17T00:29:33.956305Z","end":"2026-04-17T00:29:34.076676Z","steps":["trace[1533125569] 'process raft request' (duration: 66.286688ms)","trace[1533125569] 'compare' (duration: 53.757454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:36.416066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.086888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:36.416293Z","caller":"traceutil/trace.go:171","msg":"trace[1245126042] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"133.657983ms","start":"2026-04-17T00:29:36.282589Z","end":"2026-04-17T00:29:36.416247Z","steps":["trace[1245126042] 'process raft request' (duration: 24.318889ms)","trace[1245126042] 'compare' (duration: 108.933504ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:36.800457Z","caller":"traceutil/trace.go:171","msg":"trace[945843164] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"101.192829ms","start":"2026-04-17T00:29:36.699229Z","end":"2026-04-17T00:29:36.800421Z","steps":["trace[945843164] 'process raft request' (duration: 55.650235ms)","trace[945843164] 'compare' (duration: 45.318379ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:36.971716Z","caller":"traceutil/trace.go:171","msg":"trace[1446285890] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"101.105507ms","start":"2026-04-17T00:29:36.870589Z","end":"2026-04-17T00:29:36.971694Z","steps":["trace[1446285890] 'process raft request' (duration: 50.014841ms)","trace[1446285890] 'compare' (duration: 50.801079ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:37.153022Z","caller":"traceutil/trace.go:171","msg":"trace[1826072800] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"100.619955ms","start":"2026-04-17T00:29:37.052356Z","end":"2026-04-17T00:29:37.152976Z","steps":["trace[1826072800] 'process raft request' (duration: 90.395753ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:37.426877Z","caller":"traceutil/trace.go:171","msg":"trace[113725815] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"205.637783ms","start":"2026-04-17T00:29:37.221203Z","end":"2026-04-17T00:29:37.426841Z","steps":["trace[113725815] 'read index received' (duration: 152.326385ms)","trace[113725815] 'applied index is now lower than readState.Index' (duration: 53.310088ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:37.426965Z","caller":"traceutil/trace.go:171","msg":"trace[881246726] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"254.68068ms","start":"2026-04-17T00:29:37.172247Z","end":"2026-04-17T00:29:37.426928Z","steps":["trace[881246726] 'process raft request' (duration: 201.352242ms)","trace[881246726] 'compare' (duration: 52.319444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:37.427226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.007713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:37.42729Z","caller":"traceutil/trace.go:171","msg":"trace[1172703718] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:286; }","duration":"206.129015ms","start":"2026-04-17T00:29:37.221141Z","end":"2026-04-17T00:29:37.42727Z","steps":["trace[1172703718] 'agreement among raft nodes before linearized reading' (duration: 205.973832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:37.648224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.89182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-17T00:29:37.648319Z","caller":"traceutil/trace.go:171","msg":"trace[1388009146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:290; }","duration":"120.045323ms","start":"2026-04-17T00:29:37.528254Z","end":"2026-04-17T00:29:37.6483Z","steps":["trace[1388009146] 'range keys from in-memory index tree' (duration: 119.801058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:37.666853Z","caller":"traceutil/trace.go:171","msg":"trace[1135510653] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"138.395576ms","start":"2026-04-17T00:29:37.52843Z","end":"2026-04-17T00:29:37.666826Z","steps":["trace[1135510653] 'process raft request' (duration: 138.243682ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:38.147006Z","caller":"traceutil/trace.go:171","msg":"trace[73486963] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"102.521549ms","start":"2026-04-17T00:29:38.044446Z","end":"2026-04-17T00:29:38.146967Z","steps":["trace[73486963] 'process raft request' (duration: 81.749462ms)","trace[73486963] 'compare' (duration: 20.626494ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:38.306027Z","caller":"traceutil/trace.go:171","msg":"trace[933253229] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"124.487063ms","start":"2026-04-17T00:29:38.181512Z","end":"2026-04-17T00:29:38.305999Z","steps":["trace[933253229] 'process raft request' (duration: 66.976317ms)","trace[933253229] 'compare' (duration: 57.361552ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:38.315847Z","caller":"traceutil/trace.go:171","msg":"trace[863576698] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"108.851207ms","start":"2026-04-17T00:29:38.206978Z","end":"2026-04-17T00:29:38.315829Z","steps":["trace[863576698] 'process raft request' (duration: 108.702334ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:38.31632Z","caller":"traceutil/trace.go:171","msg":"trace[1619450568] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"109.529912ms","start":"2026-04-17T00:29:38.206267Z","end":"2026-04-17T00:29:38.315797Z","steps":["trace[1619450568] 'process raft request' (duration: 109.325178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:38.516096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.495532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:38.516242Z","caller":"traceutil/trace.go:171","msg":"trace[1073137407] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:318; }","duration":"139.719989ms","start":"2026-04-17T00:29:38.376512Z","end":"2026-04-17T00:29:38.516232Z","steps":["trace[1073137407] 'read index received' (duration: 35.972182ms)","trace[1073137407] 'applied index is now lower than readState.Index' (duration: 103.747227ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:38.516335Z","caller":"traceutil/trace.go:171","msg":"trace[458020479] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"196.905668ms","start":"2026-04-17T00:29:38.319425Z","end":"2026-04-17T00:29:38.51633Z","steps":["trace[458020479] 'process raft request' (duration: 196.739824ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:38.516268Z","caller":"traceutil/trace.go:171","msg":"trace[1027670642] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"197.4224ms","start":"2026-04-17T00:29:38.31882Z","end":"2026-04-17T00:29:38.516243Z","steps":["trace[1027670642] 'process raft request' (duration: 93.726254ms)","trace[1027670642] 'compare' (duration: 103.393729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:38.516508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.336114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4130"} {"level":"info","ts":"2026-04-17T00:29:38.516548Z","caller":"traceutil/trace.go:171","msg":"trace[1316284319] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:308; }","duration":"199.386064ms","start":"2026-04-17T00:29:38.317156Z","end":"2026-04-17T00:29:38.516542Z","steps":["trace[1316284319] 'agreement among raft nodes before linearized reading' (duration: 199.329263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:38.516559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.19551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-17T00:29:38.516613Z","caller":"traceutil/trace.go:171","msg":"trace[728073409] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"199.294893ms","start":"2026-04-17T00:29:38.317303Z","end":"2026-04-17T00:29:38.516598Z","steps":["trace[728073409] 'agreement among raft nodes before linearized reading' (duration: 199.1702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:38.516706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.6363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"info","ts":"2026-04-17T00:29:38.516723Z","caller":"traceutil/trace.go:171","msg":"trace[382261737] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:308; }","duration":"199.683072ms","start":"2026-04-17T00:29:38.317036Z","end":"2026-04-17T00:29:38.516719Z","steps":["trace[382261737] 'agreement among raft nodes before linearized reading' (duration: 199.62737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:38.516441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.230562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5325"} {"level":"warn","ts":"2026-04-17T00:29:38.516881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.658361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-17T00:29:38.516951Z","caller":"traceutil/trace.go:171","msg":"trace[1334886290] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:308; }","duration":"199.735773ms","start":"2026-04-17T00:29:38.317199Z","end":"2026-04-17T00:29:38.516935Z","steps":["trace[1334886290] 'agreement among raft nodes before linearized reading' (duration: 199.62952ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:38.517046Z","caller":"traceutil/trace.go:171","msg":"trace[950913570] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:308; }","duration":"199.741893ms","start":"2026-04-17T00:29:38.317182Z","end":"2026-04-17T00:29:38.516924Z","steps":["trace[950913570] 'agreement among raft nodes before linearized reading' (duration: 199.16733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:38.539935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.129293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-04-17T00:29:38.540014Z","caller":"traceutil/trace.go:171","msg":"trace[1525056377] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:309; }","duration":"156.239246ms","start":"2026-04-17T00:29:38.383753Z","end":"2026-04-17T00:29:38.539993Z","steps":["trace[1525056377] 'agreement among raft nodes before linearized reading' (duration: 156.116233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.047258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.459961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:39.047514Z","caller":"traceutil/trace.go:171","msg":"trace[1061792583] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"527.38612ms","start":"2026-04-17T00:29:38.520094Z","end":"2026-04-17T00:29:39.04748Z","steps":["trace[1061792583] 'process raft request' (duration: 335.622942ms)","trace[1061792583] 'compare' (duration: 191.316017ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:39.047618Z","caller":"traceutil/trace.go:171","msg":"trace[543707496] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"507.74305ms","start":"2026-04-17T00:29:38.539849Z","end":"2026-04-17T00:29:39.047592Z","steps":["trace[543707496] 'read index received' (duration: 315.982432ms)","trace[543707496] 'applied index is now lower than readState.Index' (duration: 191.756758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:39.047694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:38.520082Z","time spent":"527.501593ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:39.047865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.671527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-17T00:29:39.047908Z","caller":"traceutil/trace.go:171","msg":"trace[1600993013] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:311; }","duration":"527.761328ms","start":"2026-04-17T00:29:38.520137Z","end":"2026-04-17T00:29:39.047898Z","steps":["trace[1600993013] 'agreement among raft nodes before linearized reading' (duration: 527.618995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.047957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:38.520124Z","time spent":"527.81275ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-04-17T00:29:39.0481Z","caller":"traceutil/trace.go:171","msg":"trace[400166042] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"506.922011ms","start":"2026-04-17T00:29:38.541151Z","end":"2026-04-17T00:29:39.048073Z","steps":["trace[400166042] 'process raft request' (duration: 506.308656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.048321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.659306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-17T00:29:39.048415Z","caller":"traceutil/trace.go:171","msg":"trace[1426342775] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"528.21695ms","start":"2026-04-17T00:29:38.52018Z","end":"2026-04-17T00:29:39.048396Z","steps":["trace[1426342775] 'agreement among raft nodes before linearized reading' (duration: 527.584534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.048464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:38.52014Z","time spent":"528.308762ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T00:29:39.048324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:38.541127Z","time spent":"507.137957ms","remote":"127.0.0.1:38760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:39.200486Z","caller":"traceutil/trace.go:171","msg":"trace[2004758413] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"147.970092ms","start":"2026-04-17T00:29:39.052481Z","end":"2026-04-17T00:29:39.200451Z","steps":["trace[2004758413] 'process raft request' (duration: 145.120456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.20061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.771265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T00:29:39.200657Z","caller":"traceutil/trace.go:171","msg":"trace[437513190] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"137.864488ms","start":"2026-04-17T00:29:39.062783Z","end":"2026-04-17T00:29:39.200647Z","steps":["trace[437513190] 'agreement among raft nodes before linearized reading' (duration: 137.772176ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:39.200486Z","caller":"traceutil/trace.go:171","msg":"trace[1318449712] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"146.786184ms","start":"2026-04-17T00:29:39.053666Z","end":"2026-04-17T00:29:39.200453Z","steps":["trace[1318449712] 'process raft request' (duration: 146.656061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:39.2005Z","caller":"traceutil/trace.go:171","msg":"trace[888451556] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:324; }","duration":"137.653723ms","start":"2026-04-17T00:29:39.062822Z","end":"2026-04-17T00:29:39.200476Z","steps":["trace[888451556] 'read index received' (duration: 134.721715ms)","trace[888451556] 'applied index is now lower than readState.Index' (duration: 2.930818ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:39.200575Z","caller":"traceutil/trace.go:171","msg":"trace[1826231279] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"142.479375ms","start":"2026-04-17T00:29:39.05808Z","end":"2026-04-17T00:29:39.200559Z","steps":["trace[1826231279] 'process raft request' (duration: 142.29092ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:39.250871Z","caller":"traceutil/trace.go:171","msg":"trace[151641839] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"185.054212ms","start":"2026-04-17T00:29:39.065787Z","end":"2026-04-17T00:29:39.250842Z","steps":["trace[151641839] 'process raft request' (duration: 184.825477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.251587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.036517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5325"} {"level":"info","ts":"2026-04-17T00:29:39.251648Z","caller":"traceutil/trace.go:171","msg":"trace[1714218342] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:316; }","duration":"102.130428ms","start":"2026-04-17T00:29:39.149504Z","end":"2026-04-17T00:29:39.251634Z","steps":["trace[1714218342] 'agreement among raft nodes before linearized reading' (duration: 102.005625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.449783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.423176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:39.450027Z","caller":"traceutil/trace.go:171","msg":"trace[1668965071] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"189.320272ms","start":"2026-04-17T00:29:39.260686Z","end":"2026-04-17T00:29:39.450006Z","steps":["trace[1668965071] 'process raft request' (duration: 189.21855ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:39.450063Z","caller":"traceutil/trace.go:171","msg":"trace[1266808291] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"191.336248ms","start":"2026-04-17T00:29:39.258672Z","end":"2026-04-17T00:29:39.450008Z","steps":["trace[1266808291] 'process raft request' (duration: 82.37362ms)","trace[1266808291] 'compare' (duration: 108.218761ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:39.450263Z","caller":"traceutil/trace.go:171","msg":"trace[293956709] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:332; }","duration":"164.109786ms","start":"2026-04-17T00:29:39.286117Z","end":"2026-04-17T00:29:39.450227Z","steps":["trace[293956709] 'read index received' (duration: 54.914283ms)","trace[293956709] 'applied index is now lower than readState.Index' (duration: 109.191033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:39.451662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.316055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:39.451751Z","caller":"traceutil/trace.go:171","msg":"trace[461796910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:319; }","duration":"162.431088ms","start":"2026-04-17T00:29:39.289281Z","end":"2026-04-17T00:29:39.451713Z","steps":["trace[461796910] 'agreement among raft nodes before linearized reading' (duration: 162.273505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.452384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.774555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7293"} {"level":"info","ts":"2026-04-17T00:29:39.452495Z","caller":"traceutil/trace.go:171","msg":"trace[2012365442] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:319; }","duration":"199.906368ms","start":"2026-04-17T00:29:39.252564Z","end":"2026-04-17T00:29:39.452471Z","steps":["trace[2012365442] 'agreement among raft nodes before linearized reading' (duration: 199.736954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.455548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.938255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-17T00:29:39.455615Z","caller":"traceutil/trace.go:171","msg":"trace[1203223352] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:319; }","duration":"202.706882ms","start":"2026-04-17T00:29:39.252888Z","end":"2026-04-17T00:29:39.455595Z","steps":["trace[1203223352] 'agreement among raft nodes before linearized reading' (duration: 197.401619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.697519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.254402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:39.697724Z","caller":"traceutil/trace.go:171","msg":"trace[1731537298] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:335; }","duration":"216.387757ms","start":"2026-04-17T00:29:39.481315Z","end":"2026-04-17T00:29:39.697703Z","steps":["trace[1731537298] 'read index received' (duration: 2.5963ms)","trace[1731537298] 'applied index is now lower than readState.Index' (duration: 213.790327ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:39.69785Z","caller":"traceutil/trace.go:171","msg":"trace[4943560] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"233.431043ms","start":"2026-04-17T00:29:39.464404Z","end":"2026-04-17T00:29:39.697835Z","steps":["trace[4943560] 'process raft request' (duration: 117.780792ms)","trace[4943560] 'compare' (duration: 115.110609ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:39.698046Z","caller":"traceutil/trace.go:171","msg":"trace[459890642] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"233.388153ms","start":"2026-04-17T00:29:39.464629Z","end":"2026-04-17T00:29:39.698017Z","steps":["trace[459890642] 'process raft request' (duration: 232.993773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.698064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.895351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:39.698118Z","caller":"traceutil/trace.go:171","msg":"trace[961258396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:323; }","duration":"210.971913ms","start":"2026-04-17T00:29:39.48713Z","end":"2026-04-17T00:29:39.698102Z","steps":["trace[961258396] 'agreement among raft nodes before linearized reading' (duration: 210.862871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:39.698243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.842769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T00:29:39.69837Z","caller":"traceutil/trace.go:171","msg":"trace[237168774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:323; }","duration":"217.058314ms","start":"2026-04-17T00:29:39.481288Z","end":"2026-04-17T00:29:39.698346Z","steps":["trace[237168774] 'agreement among raft nodes before linearized reading' (duration: 216.614593ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:40.008827Z","caller":"traceutil/trace.go:171","msg":"trace[1905047536] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"305.068814ms","start":"2026-04-17T00:29:39.703717Z","end":"2026-04-17T00:29:40.008786Z","steps":["trace[1905047536] 'process raft request' (duration: 210.934022ms)","trace[1905047536] 'compare' (duration: 93.711602ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:40.009015Z","caller":"traceutil/trace.go:171","msg":"trace[185309474] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"232.016361ms","start":"2026-04-17T00:29:39.776983Z","end":"2026-04-17T00:29:40.009Z","steps":["trace[185309474] 'process raft request' (duration: 231.96939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.009108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:39.703709Z","time spent":"305.275669ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:40.009266Z","caller":"traceutil/trace.go:171","msg":"trace[750803617] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"304.741337ms","start":"2026-04-17T00:29:39.704495Z","end":"2026-04-17T00:29:40.009236Z","steps":["trace[750803617] 'process raft request' (duration: 304.04518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.009518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:39.704479Z","time spent":"304.968362ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:40.009439Z","caller":"traceutil/trace.go:171","msg":"trace[2136816274] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"301.357238ms","start":"2026-04-17T00:29:39.707981Z","end":"2026-04-17T00:29:40.009339Z","steps":["trace[2136816274] 'process raft request' (duration: 300.826205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.010088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:39.707968Z","time spent":"301.843889ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:29:40.604443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.90761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:40.604554Z","caller":"traceutil/trace.go:171","msg":"trace[787657315] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"511.986192ms","start":"2026-04-17T00:29:40.092551Z","end":"2026-04-17T00:29:40.604537Z","steps":["trace[787657315] 'read index received' (duration: 223.955549ms)","trace[787657315] 'applied index is now lower than readState.Index' (duration: 288.029583ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:40.60461Z","caller":"traceutil/trace.go:171","msg":"trace[1564031169] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"581.50038ms","start":"2026-04-17T00:29:40.023061Z","end":"2026-04-17T00:29:40.604561Z","steps":["trace[1564031169] 'process raft request' (duration: 293.377025ms)","trace[1564031169] 'compare' (duration: 287.762006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:40.604651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.784818ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:40.604719Z","caller":"traceutil/trace.go:171","msg":"trace[331831883] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:330; }","duration":"174.85612ms","start":"2026-04-17T00:29:40.429855Z","end":"2026-04-17T00:29:40.604711Z","steps":["trace[331831883] 'agreement among raft nodes before linearized reading' (duration: 174.770698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.604708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.127105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:40.604778Z","caller":"traceutil/trace.go:171","msg":"trace[342430190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:330; }","duration":"512.238518ms","start":"2026-04-17T00:29:40.092525Z","end":"2026-04-17T00:29:40.604763Z","steps":["trace[342430190] 'agreement among raft nodes before linearized reading' (duration: 512.091805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.604799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:40.023047Z","time spent":"581.643464ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:29:40.604818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:40.092512Z","time spent":"512.299349ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-17T00:29:40.604835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.661079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:40.604944Z","caller":"traceutil/trace.go:171","msg":"trace[1310627479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:330; }","duration":"172.787122ms","start":"2026-04-17T00:29:40.43213Z","end":"2026-04-17T00:29:40.604917Z","steps":["trace[1310627479] 'agreement among raft nodes before linearized reading' (duration: 172.591757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.968938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.075205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:40.969289Z","caller":"traceutil/trace.go:171","msg":"trace[1365842599] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"355.868093ms","start":"2026-04-17T00:29:40.613269Z","end":"2026-04-17T00:29:40.969137Z","steps":["trace[1365842599] 'process raft request' (duration: 250.48304ms)","trace[1365842599] 'compare' (duration: 104.84086ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:40.969253Z","caller":"traceutil/trace.go:171","msg":"trace[1356858701] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"274.839059ms","start":"2026-04-17T00:29:40.694355Z","end":"2026-04-17T00:29:40.969195Z","steps":["trace[1356858701] 'read index received' (duration: 169.359974ms)","trace[1356858701] 'applied index is now lower than readState.Index' (duration: 105.476935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:40.969368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.004713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:40.969526Z","caller":"traceutil/trace.go:171","msg":"trace[1466493448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:332; }","duration":"275.193377ms","start":"2026-04-17T00:29:40.69432Z","end":"2026-04-17T00:29:40.969513Z","steps":["trace[1466493448] 'agreement among raft nodes before linearized reading' (duration: 274.965022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:40.969747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:40.613249Z","time spent":"356.154699ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:41.142298Z","caller":"traceutil/trace.go:171","msg":"trace[1018529906] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"127.965247ms","start":"2026-04-17T00:29:41.014303Z","end":"2026-04-17T00:29:41.142268Z","steps":["trace[1018529906] 'process raft request' (duration: 63.040677ms)","trace[1018529906] 'compare' (duration: 64.769877ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:41.154728Z","caller":"traceutil/trace.go:171","msg":"trace[404640645] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:349; }","duration":"132.143973ms","start":"2026-04-17T00:29:41.022565Z","end":"2026-04-17T00:29:41.154709Z","steps":["trace[404640645] 'read index received' (duration: 54.779929ms)","trace[404640645] 'applied index is now lower than readState.Index' (duration: 77.363124ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:41.154835Z","caller":"traceutil/trace.go:171","msg":"trace[102295374] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"136.87701ms","start":"2026-04-17T00:29:41.017913Z","end":"2026-04-17T00:29:41.15479Z","steps":["trace[102295374] 'process raft request' (duration: 136.698415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:41.154861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.276676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:41.155063Z","caller":"traceutil/trace.go:171","msg":"trace[255263633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:336; }","duration":"132.503291ms","start":"2026-04-17T00:29:41.022536Z","end":"2026-04-17T00:29:41.155039Z","steps":["trace[255263633] 'agreement among raft nodes before linearized reading' (duration: 132.279216ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:41.945892Z","caller":"traceutil/trace.go:171","msg":"trace[572258207] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"139.170343ms","start":"2026-04-17T00:29:41.806697Z","end":"2026-04-17T00:29:41.945867Z","steps":["trace[572258207] 'read index received' (duration: 74.686753ms)","trace[572258207] 'applied index is now lower than readState.Index' (duration: 64.48161ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:41.945909Z","caller":"traceutil/trace.go:171","msg":"trace[32117898] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"128.452518ms","start":"2026-04-17T00:29:41.817423Z","end":"2026-04-17T00:29:41.945875Z","steps":["trace[32117898] 'process raft request' (duration: 128.356596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:41.946034Z","caller":"traceutil/trace.go:171","msg":"trace[1657201130] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"159.047995ms","start":"2026-04-17T00:29:41.786971Z","end":"2026-04-17T00:29:41.946019Z","steps":["trace[1657201130] 'process raft request' (duration: 94.394831ms)","trace[1657201130] 'compare' (duration: 64.237415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:41.946296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.182905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-17T00:29:41.946332Z","caller":"traceutil/trace.go:171","msg":"trace[1386763347] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:375; }","duration":"158.241177ms","start":"2026-04-17T00:29:41.788079Z","end":"2026-04-17T00:29:41.94632Z","steps":["trace[1386763347] 'agreement among raft nodes before linearized reading' (duration: 158.144515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:41.946494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.873977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-04-17T00:29:41.946555Z","caller":"traceutil/trace.go:171","msg":"trace[273849620] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:375; }","duration":"128.96498ms","start":"2026-04-17T00:29:41.817573Z","end":"2026-04-17T00:29:41.946538Z","steps":["trace[273849620] 'agreement among raft nodes before linearized reading' (duration: 128.837237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:41.946726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.310201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-17T00:29:41.946766Z","caller":"traceutil/trace.go:171","msg":"trace[1188936981] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:375; }","duration":"123.378763ms","start":"2026-04-17T00:29:41.823375Z","end":"2026-04-17T00:29:41.946754Z","steps":["trace[1188936981] 'agreement among raft nodes before linearized reading' (duration: 123.309071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:43.590532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.362151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-17T00:29:43.590613Z","caller":"traceutil/trace.go:171","msg":"trace[494767772] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:396; }","duration":"188.507594ms","start":"2026-04-17T00:29:43.402088Z","end":"2026-04-17T00:29:43.590596Z","steps":["trace[494767772] 'range keys from in-memory index tree' (duration: 188.254438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:45.808908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967328886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:29:46.033588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.107798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:46.03377Z","caller":"traceutil/trace.go:171","msg":"trace[1735722737] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.132051923s","start":"2026-04-17T00:29:44.901692Z","end":"2026-04-17T00:29:46.033744Z","steps":["trace[1735722737] 'process raft request' (duration: 267.683949ms)","trace[1735722737] 'compare' (duration: 863.856653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:46.0339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:44.901679Z","time spent":"1.132143635s","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:29:46.249156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0796622s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T00:29:46.387602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.107149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:29:46.387793Z","caller":"traceutil/trace.go:171","msg":"trace[1373645404] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.485181792s","start":"2026-04-17T00:29:44.902589Z","end":"2026-04-17T00:29:46.387771Z","steps":["trace[1373645404] 'process raft request' (duration: 1.346816178s)","trace[1373645404] 'compare' (duration: 137.906954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:46.387868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:44.902581Z","time spent":"1.485253385s","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:46.387931Z","caller":"traceutil/trace.go:171","msg":"trace[1088558319] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"349.284515ms","start":"2026-04-17T00:29:46.038631Z","end":"2026-04-17T00:29:46.387916Z","steps":["trace[1088558319] 'process raft request' (duration: 349.243595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:46.038602Z","time spent":"349.379638ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:29:46.388119Z","caller":"traceutil/trace.go:171","msg":"trace[505729458] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:416; }","duration":"1.080070045s","start":"2026-04-17T00:29:45.308002Z","end":"2026-04-17T00:29:46.388072Z","steps":["trace[505729458] 'read index received' (duration: 941.417434ms)","trace[505729458] 'applied index is now lower than readState.Index' (duration: 138.650261ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:46.388199Z","caller":"traceutil/trace.go:171","msg":"trace[2103672719] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.483351701s","start":"2026-04-17T00:29:44.904749Z","end":"2026-04-17T00:29:46.388101Z","steps":["trace[2103672719] 'process raft request' (duration: 1.482960463s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048250683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T00:29:46.388331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.470211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T00:29:46.388337Z","caller":"traceutil/trace.go:171","msg":"trace[1750137466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"1.048334585s","start":"2026-04-17T00:29:45.339992Z","end":"2026-04-17T00:29:46.388326Z","steps":["trace[1750137466] 'agreement among raft nodes before linearized reading' (duration: 1.048258734s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.494315ms","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-04-17T00:29:46.388357Z","caller":"traceutil/trace.go:171","msg":"trace[350178380] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:405; }","duration":"912.526023ms","start":"2026-04-17T00:29:45.475823Z","end":"2026-04-17T00:29:46.388349Z","steps":["trace[350178380] 'agreement among raft nodes before linearized reading' (duration: 912.463241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:45.339968Z","time spent":"1.048393266s","remote":"127.0.0.1:39008","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-04-17T00:29:46.388351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.103303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:29:46.388384Z","caller":"traceutil/trace.go:171","msg":"trace[1288386809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"266.644878ms","start":"2026-04-17T00:29:46.121726Z","end":"2026-04-17T00:29:46.388371Z","steps":["trace[1288386809] 'agreement among raft nodes before linearized reading' (duration: 266.547306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.388433Z","caller":"traceutil/trace.go:171","msg":"trace[1121393942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"366.220315ms","start":"2026-04-17T00:29:46.022194Z","end":"2026-04-17T00:29:46.388415Z","steps":["trace[1121393942] 'agreement among raft nodes before linearized reading' (duration: 366.090232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080242118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-17T00:29:46.388477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:46.022176Z","time spent":"366.292577ms","remote":"127.0.0.1:38718","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-04-17T00:29:46.388491Z","caller":"traceutil/trace.go:171","msg":"trace[2064018679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"1.080514644s","start":"2026-04-17T00:29:45.307959Z","end":"2026-04-17T00:29:46.388474Z","steps":["trace[2064018679] 'agreement among raft nodes before linearized reading' (duration: 1.080248839s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.388381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:45.475803Z","time spent":"912.573373ms","remote":"127.0.0.1:38740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-04-17T00:29:46.388554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:45.30794Z","time spent":"1.080598697s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T00:29:46.388597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:44.904719Z","time spent":"1.483573197s","remote":"127.0.0.1:39008","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-04-17T00:29:46.388792Z","caller":"traceutil/trace.go:171","msg":"trace[1668522613] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"1.478503764s","start":"2026-04-17T00:29:44.910269Z","end":"2026-04-17T00:29:46.388773Z","steps":["trace[1668522613] 'process raft request' (duration: 1.477556364s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.3889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:44.910247Z","time spent":"1.478611346s","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:29:46.714111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:46.390492Z","time spent":"323.612223ms","remote":"127.0.0.1:38740","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T00:29:46.714296Z","caller":"traceutil/trace.go:171","msg":"trace[1142874946] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"262.591695ms","start":"2026-04-17T00:29:46.451679Z","end":"2026-04-17T00:29:46.71427Z","steps":["trace[1142874946] 'process raft request' (duration: 262.35493ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.7147Z","caller":"traceutil/trace.go:171","msg":"trace[733596066] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"260.412068ms","start":"2026-04-17T00:29:46.454252Z","end":"2026-04-17T00:29:46.714664Z","steps":["trace[733596066] 'process raft request' (duration: 259.963778ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.714776Z","caller":"traceutil/trace.go:171","msg":"trace[2021541229] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"262.970884ms","start":"2026-04-17T00:29:46.451786Z","end":"2026-04-17T00:29:46.714757Z","steps":["trace[2021541229] 'read index received' (duration: 221.955218ms)","trace[2021541229] 'applied index is now lower than readState.Index' (duration: 41.014176ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:46.714916Z","caller":"traceutil/trace.go:171","msg":"trace[1085174000] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"257.188466ms","start":"2026-04-17T00:29:46.457697Z","end":"2026-04-17T00:29:46.714886Z","steps":["trace[1085174000] 'process raft request' (duration: 256.995582ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.715034Z","caller":"traceutil/trace.go:171","msg":"trace[191289449] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"260.347636ms","start":"2026-04-17T00:29:46.454668Z","end":"2026-04-17T00:29:46.715015Z","steps":["trace[191289449] 'process raft request' (duration: 259.972648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:46.71534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.529155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-kvmkg\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-17T00:29:46.715382Z","caller":"traceutil/trace.go:171","msg":"trace[1193427537] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-kvmkg; range_end:; response_count:1; response_revision:410; }","duration":"263.598818ms","start":"2026-04-17T00:29:46.451769Z","end":"2026-04-17T00:29:46.715368Z","steps":["trace[1193427537] 'agreement among raft nodes before linearized reading' (duration: 263.039055ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.715421Z","caller":"traceutil/trace.go:171","msg":"trace[1986399279] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"260.322525ms","start":"2026-04-17T00:29:46.454423Z","end":"2026-04-17T00:29:46.714745Z","steps":["trace[1986399279] 'process raft request' (duration: 260.08735ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:46.907374Z","caller":"traceutil/trace.go:171","msg":"trace[341345320] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"181.895075ms","start":"2026-04-17T00:29:46.725452Z","end":"2026-04-17T00:29:46.907347Z","steps":["trace[341345320] 'read index received' (duration: 2.542855ms)","trace[341345320] 'applied index is now lower than readState.Index' (duration: 179.35126ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:46.907511Z","caller":"traceutil/trace.go:171","msg":"trace[1499308910] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"183.03294ms","start":"2026-04-17T00:29:46.724429Z","end":"2026-04-17T00:29:46.907462Z","steps":["trace[1499308910] 'process raft request' (duration: 119.88305ms)","trace[1499308910] 'compare' (duration: 62.884484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:46.907672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.07903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-17T00:29:46.907722Z","caller":"traceutil/trace.go:171","msg":"trace[1478741809] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:413; }","duration":"182.275234ms","start":"2026-04-17T00:29:46.725432Z","end":"2026-04-17T00:29:46.907707Z","steps":["trace[1478741809] 'agreement among raft nodes before linearized reading' (duration: 182.017358ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:47.667704Z","caller":"traceutil/trace.go:171","msg":"trace[2118276735] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"161.50139ms","start":"2026-04-17T00:29:47.506185Z","end":"2026-04-17T00:29:47.667687Z","steps":["trace[2118276735] 'process raft request' (duration: 161.396888ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:48.636253Z","caller":"traceutil/trace.go:171","msg":"trace[667045440] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"243.039886ms","start":"2026-04-17T00:29:48.39319Z","end":"2026-04-17T00:29:48.63623Z","steps":["trace[667045440] 'read index received' (duration: 147.996552ms)","trace[667045440] 'applied index is now lower than readState.Index' (duration: 95.042424ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:48.636291Z","caller":"traceutil/trace.go:171","msg":"trace[820937301] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"444.452146ms","start":"2026-04-17T00:29:48.191783Z","end":"2026-04-17T00:29:48.636235Z","steps":["trace[820937301] 'process raft request' (duration: 349.389922ms)","trace[820937301] 'compare' (duration: 94.753297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:29:48.636407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.2044ms","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-04-17T00:29:48.636472Z","caller":"traceutil/trace.go:171","msg":"trace[1886174101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"243.306002ms","start":"2026-04-17T00:29:48.393133Z","end":"2026-04-17T00:29:48.636439Z","steps":["trace[1886174101] 'agreement among raft nodes before linearized reading' (duration: 243.186869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:29:48.636468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:29:48.19177Z","time spent":"444.596548ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:29:48.636586Z","caller":"traceutil/trace.go:171","msg":"trace[989690858] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"278.195162ms","start":"2026-04-17T00:29:48.35838Z","end":"2026-04-17T00:29:48.636575Z","steps":["trace[989690858] 'process raft request' (duration: 277.754833ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:03.484473Z","caller":"traceutil/trace.go:171","msg":"trace[147077693] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"584.303515ms","start":"2026-04-17T00:30:02.900146Z","end":"2026-04-17T00:30:03.48445Z","steps":["trace[147077693] 'process raft request' (duration: 584.175011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:03.48462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:02.90012Z","time spent":"584.429647ms","remote":"127.0.0.1:39008","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-04-17T00:30:03.485529Z","caller":"traceutil/trace.go:171","msg":"trace[1894308518] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"462.699089ms","start":"2026-04-17T00:30:03.022809Z","end":"2026-04-17T00:30:03.485508Z","steps":["trace[1894308518] 'read index received' (duration: 461.653799ms)","trace[1894308518] 'applied index is now lower than readState.Index' (duration: 1.0445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:03.48568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.858921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:03.485717Z","caller":"traceutil/trace.go:171","msg":"trace[669383037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"462.915352ms","start":"2026-04-17T00:30:03.022793Z","end":"2026-04-17T00:30:03.485708Z","steps":["trace[669383037] 'agreement among raft nodes before linearized reading' (duration: 462.79934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:03.485751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:03.022712Z","time spent":"463.031295ms","remote":"127.0.0.1:38718","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-04-17T00:30:03.486007Z","caller":"traceutil/trace.go:171","msg":"trace[571080803] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"585.761362ms","start":"2026-04-17T00:30:02.900233Z","end":"2026-04-17T00:30:03.485995Z","steps":["trace[571080803] 'process raft request' (duration: 585.15956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:03.486109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:02.900202Z","time spent":"585.836894ms","remote":"127.0.0.1:39008","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-04-17T00:30:09.324311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:09.71769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.347157315s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:30:09.72506Z","caller":"traceutil/trace.go:171","msg":"trace[1888689139] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.354657573s","start":"2026-04-17T00:30:08.370368Z","end":"2026-04-17T00:30:09.725026Z","steps":["trace[1888689139] 'process raft request' (duration: 1.347548003s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.725031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.942155ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:09.725311Z","caller":"traceutil/trace.go:171","msg":"trace[1904596528] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:542; }","duration":"114.449454ms","start":"2026-04-17T00:30:09.610834Z","end":"2026-04-17T00:30:09.725283Z","steps":["trace[1904596528] 'range keys from in-memory index tree' (duration: 113.928994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.725309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.370349Z","time spent":"1.354894287s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.743766Z","caller":"traceutil/trace.go:171","msg":"trace[123598813] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"1.373186454s","start":"2026-04-17T00:30:08.370559Z","end":"2026-04-17T00:30:09.743746Z","steps":["trace[123598813] 'process raft request' (duration: 1.37297394s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.743905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.370544Z","time spent":"1.373281786s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:09.744038Z","caller":"traceutil/trace.go:171","msg":"trace[534656655] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.373346907s","start":"2026-04-17T00:30:08.370667Z","end":"2026-04-17T00:30:09.744013Z","steps":["trace[534656655] 'process raft request' (duration: 1.37297158s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.744187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.370661Z","time spent":"1.373452249s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.744227Z","caller":"traceutil/trace.go:171","msg":"trace[288295985] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.357497384s","start":"2026-04-17T00:30:08.386658Z","end":"2026-04-17T00:30:09.744155Z","steps":["trace[288295985] 'process raft request' (duration: 1.357197768s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.744243Z","caller":"traceutil/trace.go:171","msg":"trace[170223104] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.364710797s","start":"2026-04-17T00:30:08.379496Z","end":"2026-04-17T00:30:09.744207Z","steps":["trace[170223104] 'process raft request' (duration: 1.364207798s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.744323Z","caller":"traceutil/trace.go:171","msg":"trace[1717860568] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.353954868s","start":"2026-04-17T00:30:08.39035Z","end":"2026-04-17T00:30:09.744305Z","steps":["trace[1717860568] 'process raft request' (duration: 1.353575462s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.744375Z","caller":"traceutil/trace.go:171","msg":"trace[2128101541] transaction","detail":"{read_only:false; number_of_response:0; response_revision:546; }","duration":"1.359593413s","start":"2026-04-17T00:30:08.384774Z","end":"2026-04-17T00:30:09.744367Z","steps":["trace[2128101541] 'process raft request' (duration: 1.359020102s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.744194Z","caller":"traceutil/trace.go:171","msg":"trace[420180784] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.360205975s","start":"2026-04-17T00:30:08.38383Z","end":"2026-04-17T00:30:09.744036Z","steps":["trace[420180784] 'process raft request' (duration: 1.35992616s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.747249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.383821Z","time spent":"1.361791364s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:30:09.747652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.379483Z","time spent":"1.36484459s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:09.747861Z","caller":"traceutil/trace.go:171","msg":"trace[710633950] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.352851588s","start":"2026-04-17T00:30:08.394766Z","end":"2026-04-17T00:30:09.747618Z","steps":["trace[710633950] 'process raft request' (duration: 1.349402675s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.748044Z","caller":"traceutil/trace.go:171","msg":"trace[677135601] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.359862197s","start":"2026-04-17T00:30:08.38807Z","end":"2026-04-17T00:30:09.747932Z","steps":["trace[677135601] 'process raft request' (duration: 1.355814523s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.748124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.388064Z","time spent":"1.36001734s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:09.748215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.39476Z","time spent":"1.353202324s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.747316Z","caller":"traceutil/trace.go:171","msg":"trace[192794020] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.352588643s","start":"2026-04-17T00:30:08.391741Z","end":"2026-04-17T00:30:09.74433Z","steps":["trace[192794020] 'process raft request' (duration: 1.352230676s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.748751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.391735Z","time spent":"1.356945833s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:09.747374Z","caller":"traceutil/trace.go:171","msg":"trace[443670640] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.357723258s","start":"2026-04-17T00:30:08.386623Z","end":"2026-04-17T00:30:09.744346Z","steps":["trace[443670640] 'process raft request' (duration: 1.357195239s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.748893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.386617Z","time spent":"1.362216961s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.745464Z","caller":"traceutil/trace.go:171","msg":"trace[886815668] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.353400489s","start":"2026-04-17T00:30:08.392023Z","end":"2026-04-17T00:30:09.745423Z","steps":["trace[886815668] 'process raft request' (duration: 1.351997512s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:09.749258Z","caller":"traceutil/trace.go:171","msg":"trace[2033173111] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.351063304s","start":"2026-04-17T00:30:08.398171Z","end":"2026-04-17T00:30:09.749234Z","steps":["trace[2033173111] 'process raft request' (duration: 1.347233554s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.750949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.398165Z","time spent":"1.352699694s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:09.749332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.384768Z","time spent":"1.364281359s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:30:09.749371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.392017Z","time spent":"1.357280909s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:09.748485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.390343Z","time spent":"1.35402805s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:09.749382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.386654Z","time spent":"1.357672868s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.752214Z","caller":"traceutil/trace.go:171","msg":"trace[1018652084] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.329063447s","start":"2026-04-17T00:30:08.42313Z","end":"2026-04-17T00:30:09.752194Z","steps":["trace[1018652084] 'process raft request' (duration: 1.325554442s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.752297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.423092Z","time spent":"1.329172139s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:30:09.752463Z","caller":"traceutil/trace.go:171","msg":"trace[1034298572] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:563; }","duration":"928.360352ms","start":"2026-04-17T00:30:08.824063Z","end":"2026-04-17T00:30:09.752423Z","steps":["trace[1034298572] 'read index received' (duration: 893.949809ms)","trace[1034298572] 'applied index is now lower than readState.Index' (duration: 34.409463ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:09.75277Z","caller":"traceutil/trace.go:171","msg":"trace[1254512435] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.233187413s","start":"2026-04-17T00:30:08.519568Z","end":"2026-04-17T00:30:09.752756Z","steps":["trace[1254512435] 'process raft request' (duration: 1.23250727s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.752876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.519523Z","time spent":"1.233317945s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:30:09.75618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.287879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T00:30:09.7563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.032234ms","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-04-17T00:30:09.756405Z","caller":"traceutil/trace.go:171","msg":"trace[1262125914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"250.108796ms","start":"2026-04-17T00:30:09.506225Z","end":"2026-04-17T00:30:09.756333Z","steps":["trace[1262125914] 'agreement among raft nodes before linearized reading' (duration: 250.054495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.756493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.934875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:09.756514Z","caller":"traceutil/trace.go:171","msg":"trace[189583442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"734.997396ms","start":"2026-04-17T00:30:09.021508Z","end":"2026-04-17T00:30:09.756506Z","steps":["trace[189583442] 'agreement among raft nodes before linearized reading' (duration: 734.966966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.756528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:09.021488Z","time spent":"735.035476ms","remote":"127.0.0.1:38718","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-04-17T00:30:09.756815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.764132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T00:30:09.756836Z","caller":"traceutil/trace.go:171","msg":"trace[1863825823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"840.804113ms","start":"2026-04-17T00:30:08.916026Z","end":"2026-04-17T00:30:09.75683Z","steps":["trace[1863825823] 'agreement among raft nodes before linearized reading' (duration: 840.046108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.756853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.916016Z","time spent":"840.833123ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T00:30:09.756907Z","caller":"traceutil/trace.go:171","msg":"trace[90930286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"244.419891ms","start":"2026-04-17T00:30:09.511824Z","end":"2026-04-17T00:30:09.756244Z","steps":["trace[90930286] 'agreement among raft nodes before linearized reading' (duration: 244.295559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.757218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.146471ms","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-04-17T00:30:09.757343Z","caller":"traceutil/trace.go:171","msg":"trace[1701489116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"933.320944ms","start":"2026-04-17T00:30:08.824001Z","end":"2026-04-17T00:30:09.757322Z","steps":["trace[1701489116] 'agreement among raft nodes before linearized reading' (duration: 929.023005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.757632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:08.823981Z","time spent":"933.423625ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T00:30:09.761713Z","caller":"traceutil/trace.go:171","msg":"trace[1938417679] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"611.300973ms","start":"2026-04-17T00:30:09.146826Z","end":"2026-04-17T00:30:09.758127Z","steps":["trace[1938417679] 'process raft request' (duration: 605.551878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.761845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:09.146791Z","time spent":"614.98194ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:09.762067Z","caller":"traceutil/trace.go:171","msg":"trace[1051330892] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"741.142969ms","start":"2026-04-17T00:30:09.016986Z","end":"2026-04-17T00:30:09.758129Z","steps":["trace[1051330892] 'process raft request' (duration: 735.330873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:09.762156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:09.016961Z","time spent":"745.153713ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:16.309259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:16.810383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:17.308964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.682087777s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:30:17.31041Z","caller":"traceutil/trace.go:171","msg":"trace[1024701098] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.501949107s","start":"2026-04-17T00:30:15.808436Z","end":"2026-04-17T00:30:17.310385Z","steps":["trace[1024701098] 'read index received' (duration: 1.500712946s)","trace[1024701098] 'applied index is now lower than readState.Index' (duration: 1.235351ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:17.310482Z","caller":"traceutil/trace.go:171","msg":"trace[1677523995] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.684592542s","start":"2026-04-17T00:30:15.62585Z","end":"2026-04-17T00:30:17.310443Z","steps":["trace[1677523995] 'process raft request' (duration: 1.683536684s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.310587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501804575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T00:30:17.310614Z","caller":"traceutil/trace.go:171","msg":"trace[1671259058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.501859126s","start":"2026-04-17T00:30:15.808747Z","end":"2026-04-17T00:30:17.310606Z","steps":["trace[1671259058] 'agreement among raft nodes before linearized reading' (duration: 1.501749134s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.310639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:15.808742Z","time spent":"1.501891666s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:30:17.310662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502197971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T00:30:17.310734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288702763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:17.31076Z","caller":"traceutil/trace.go:171","msg":"trace[1513186191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.502325974s","start":"2026-04-17T00:30:15.808407Z","end":"2026-04-17T00:30:17.310733Z","steps":["trace[1513186191] 'agreement among raft nodes before linearized reading' (duration: 1.50208081s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.310818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:15.80839Z","time spent":"1.502419415s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T00:30:17.310819Z","caller":"traceutil/trace.go:171","msg":"trace[2102650320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.288840696s","start":"2026-04-17T00:30:16.021956Z","end":"2026-04-17T00:30:17.310796Z","steps":["trace[2102650320] 'agreement among raft nodes before linearized reading' (duration: 1.288655443s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.310855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489271843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T00:30:17.310859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:16.021856Z","time spent":"1.288995329s","remote":"127.0.0.1:38718","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-04-17T00:30:17.310824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:15.62583Z","time spent":"1.684727834s","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:17.310924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489416316s","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-04-17T00:30:17.310944Z","caller":"traceutil/trace.go:171","msg":"trace[1783921312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.489467446s","start":"2026-04-17T00:30:15.821472Z","end":"2026-04-17T00:30:17.31094Z","steps":["trace[1783921312] 'agreement among raft nodes before linearized reading' (duration: 1.489418796s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.310961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:15.821452Z","time spent":"1.489505718s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T00:30:17.31088Z","caller":"traceutil/trace.go:171","msg":"trace[187516143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.489312453s","start":"2026-04-17T00:30:15.821562Z","end":"2026-04-17T00:30:17.310874Z","steps":["trace[187516143] 'agreement among raft nodes before linearized reading' (duration: 1.489248602s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.311022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:15.821553Z","time spent":"1.489465626s","remote":"127.0.0.1:39008","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-04-17T00:30:17.311105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05364781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4lndc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-17T00:30:17.311125Z","caller":"traceutil/trace.go:171","msg":"trace[461275083] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4lndc; range_end:; response_count:1; response_revision:640; }","duration":"1.053705541s","start":"2026-04-17T00:30:16.257415Z","end":"2026-04-17T00:30:17.31112Z","steps":["trace[461275083] 'agreement among raft nodes before linearized reading' (duration: 1.05364596s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:17.31114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:16.257398Z","time spent":"1.053739132s","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-4lndc\" "} {"level":"warn","ts":"2026-04-17T00:30:18.616418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182808151s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:30:18.616772Z","caller":"traceutil/trace.go:171","msg":"trace[413724454] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.301000861s","start":"2026-04-17T00:30:17.315746Z","end":"2026-04-17T00:30:18.616747Z","steps":["trace[413724454] 'process raft request' (duration: 1.300841198s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:18.616778Z","caller":"traceutil/trace.go:171","msg":"trace[226081828] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.30095196s","start":"2026-04-17T00:30:17.315808Z","end":"2026-04-17T00:30:18.61676Z","steps":["trace[226081828] 'process raft request' (duration: 1.300892159s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:18.616873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.315723Z","time spent":"1.301085923s","remote":"127.0.0.1:39008","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-04-17T00:30:18.616891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.315789Z","time spent":"1.301039412s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:18.617037Z","caller":"traceutil/trace.go:171","msg":"trace[661663134] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.301039312s","start":"2026-04-17T00:30:17.315987Z","end":"2026-04-17T00:30:18.617026Z","steps":["trace[661663134] 'process raft request' (duration: 1.300741627s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:18.617114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.315978Z","time spent":"1.301087032s","remote":"127.0.0.1:39008","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-04-17T00:30:18.617146Z","caller":"traceutil/trace.go:171","msg":"trace[531200029] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"1.295399664s","start":"2026-04-17T00:30:17.321739Z","end":"2026-04-17T00:30:18.617138Z","steps":["trace[531200029] 'read index received' (duration: 31.432668ms)","trace[531200029] 'applied index is now lower than readState.Index' (duration: 1.263966296s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:18.617246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295503465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:18.617265Z","caller":"traceutil/trace.go:171","msg":"trace[994697965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.295539877s","start":"2026-04-17T00:30:17.321719Z","end":"2026-04-17T00:30:18.617259Z","steps":["trace[994697965] 'agreement among raft nodes before linearized reading' (duration: 1.295446065s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:18.617282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.321711Z","time spent":"1.295568008s","remote":"127.0.0.1:38718","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-04-17T00:30:18.617297Z","caller":"traceutil/trace.go:171","msg":"trace[88176712] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.290975637s","start":"2026-04-17T00:30:17.326312Z","end":"2026-04-17T00:30:18.617287Z","steps":["trace[88176712] 'process raft request' (duration: 1.290670341s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:18.617342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.326295Z","time spent":"1.291022838s","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:18.648853Z","caller":"traceutil/trace.go:171","msg":"trace[1380855330] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.284305547s","start":"2026-04-17T00:30:17.364532Z","end":"2026-04-17T00:30:18.648838Z","steps":["trace[1380855330] 'process raft request' (duration: 1.284225996s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:18.648941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:17.364512Z","time spent":"1.284386499s","remote":"127.0.0.1:39266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:30:19.857792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:20.358389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:20.678591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066577197s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:20.67872Z","caller":"traceutil/trace.go:171","msg":"trace[737884078] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"1.066705249s","start":"2026-04-17T00:30:19.611982Z","end":"2026-04-17T00:30:20.678688Z","steps":["trace[737884078] 'range keys from in-memory index tree' (duration: 1.066561776s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:20.859346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:21.359937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:21.861063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:30:22.021959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999928804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T00:30:22.022035Z","caller":"traceutil/trace.go:171","msg":"trace[32303475] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000061477s","start":"2026-04-17T00:30:20.021957Z","end":"2026-04-17T00:30:22.022018Z","steps":["trace[32303475] 'agreement among raft nodes before linearized reading' (duration: 1.999963155s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.022087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:20.021934Z","time spent":"2.000143118s","remote":"127.0.0.1:38718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 00:30:22 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T00:30:22.115289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.075080921s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:30:22.117477Z","caller":"traceutil/trace.go:171","msg":"trace[1841369631] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"3.078705143s","start":"2026-04-17T00:30:19.038698Z","end":"2026-04-17T00:30:22.117403Z","steps":["trace[1841369631] 'process raft request' (duration: 3.077651155s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.11784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:19.038676Z","time spent":"3.078954987s","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:22.141664Z","caller":"traceutil/trace.go:171","msg":"trace[1428563537] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"2.216748197s","start":"2026-04-17T00:30:19.924887Z","end":"2026-04-17T00:30:22.141635Z","steps":["trace[1428563537] 'process raft request' (duration: 2.216019934s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:22.141686Z","caller":"traceutil/trace.go:171","msg":"trace[383168173] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.007629462s","start":"2026-04-17T00:30:20.134037Z","end":"2026-04-17T00:30:22.141667Z","steps":["trace[383168173] 'process raft request' (duration: 2.00698478s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.141808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:19.924861Z","time spent":"2.21687207s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:22.141821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:20.134013Z","time spent":"2.007726603s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:22.162194Z","caller":"traceutil/trace.go:171","msg":"trace[1849297726] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:672; }","duration":"2.80522871s","start":"2026-04-17T00:30:19.356946Z","end":"2026-04-17T00:30:22.162174Z","steps":["trace[1849297726] 'read index received' (duration: 2.758747455s)","trace[1849297726] 'applied index is now lower than readState.Index' (duration: 46.480365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:22.162402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.805445754s","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-04-17T00:30:22.162427Z","caller":"traceutil/trace.go:171","msg":"trace[1081812837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"2.805479834s","start":"2026-04-17T00:30:19.356941Z","end":"2026-04-17T00:30:22.162421Z","steps":["trace[1081812837] 'agreement among raft nodes before linearized reading' (duration: 2.805324972s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.162445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:19.356894Z","time spent":"2.805546616s","remote":"127.0.0.1:39008","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-04-17T00:30:22.162509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.985406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:22.162525Z","caller":"traceutil/trace.go:171","msg":"trace[1873914273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"140.002126ms","start":"2026-04-17T00:30:22.022519Z","end":"2026-04-17T00:30:22.162521Z","steps":["trace[1873914273] 'agreement among raft nodes before linearized reading' (duration: 139.979186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.162539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541827498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T00:30:22.162592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.316368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:30:22.162685Z","caller":"traceutil/trace.go:171","msg":"trace[466035258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"774.472921ms","start":"2026-04-17T00:30:21.388193Z","end":"2026-04-17T00:30:22.162666Z","steps":["trace[466035258] 'agreement among raft nodes before linearized reading' (duration: 774.300689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.162704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541902699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T00:30:22.162721Z","caller":"traceutil/trace.go:171","msg":"trace[22168623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"1.54193506s","start":"2026-04-17T00:30:20.620781Z","end":"2026-04-17T00:30:22.162716Z","steps":["trace[22168623] 'agreement among raft nodes before linearized reading' (duration: 1.541887789s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.162735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:20.620773Z","time spent":"1.541958871s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:30:22.16274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:21.388172Z","time spent":"774.560073ms","remote":"127.0.0.1:38718","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-04-17T00:30:22.162615Z","caller":"traceutil/trace.go:171","msg":"trace[392891152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.54194439s","start":"2026-04-17T00:30:20.620656Z","end":"2026-04-17T00:30:22.1626Z","steps":["trace[392891152] 'agreement among raft nodes before linearized reading' (duration: 1.541734147s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.162821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:20.620624Z","time spent":"1.542193724s","remote":"127.0.0.1:39008","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-04-17T00:30:22.162907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541898909s","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-04-17T00:30:22.162953Z","caller":"traceutil/trace.go:171","msg":"trace[324677471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.542118833s","start":"2026-04-17T00:30:20.620822Z","end":"2026-04-17T00:30:22.162941Z","steps":["trace[324677471] 'agreement among raft nodes before linearized reading' (duration: 1.541841298s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:22.163021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:20.620786Z","time spent":"1.542224755s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T00:30:26.653193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.670174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-17T00:30:26.653291Z","caller":"traceutil/trace.go:171","msg":"trace[1029289255] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"162.794446ms","start":"2026-04-17T00:30:26.49046Z","end":"2026-04-17T00:30:26.653254Z","steps":["trace[1029289255] 'range keys from in-memory index tree' (duration: 162.468601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.752901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954091967329747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:30:38.982006Z","caller":"traceutil/trace.go:171","msg":"trace[232524703] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"816.709649ms","start":"2026-04-17T00:30:38.165267Z","end":"2026-04-17T00:30:38.981976Z","steps":["trace[232524703] 'process raft request' (duration: 816.542847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.98223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.16525Z","time spent":"816.847951ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:30:38.98945Z","caller":"traceutil/trace.go:171","msg":"trace[1783078254] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"736.880556ms","start":"2026-04-17T00:30:38.252552Z","end":"2026-04-17T00:30:38.989433Z","steps":["trace[1783078254] 'read index received' (duration: 729.646094ms)","trace[1783078254] 'applied index is now lower than readState.Index' (duration: 7.233372ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:38.989548Z","caller":"traceutil/trace.go:171","msg":"trace[1496622798] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"616.581367ms","start":"2026-04-17T00:30:38.372957Z","end":"2026-04-17T00:30:38.989539Z","steps":["trace[1496622798] 'process raft request' (duration: 616.389014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.989641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.372931Z","time spent":"616.640938ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:38.989747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.621821ms","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-04-17T00:30:38.989808Z","caller":"traceutil/trace.go:171","msg":"trace[521576722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"723.697482ms","start":"2026-04-17T00:30:38.266101Z","end":"2026-04-17T00:30:38.989799Z","steps":["trace[521576722] 'agreement among raft nodes before linearized reading' (duration: 723.57508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.989832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.266094Z","time spent":"723.731423ms","remote":"127.0.0.1:39008","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-04-17T00:30:38.989843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.836094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-17T00:30:38.989803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.315023ms","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-04-17T00:30:38.989923Z","caller":"traceutil/trace.go:171","msg":"trace[1219179469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"723.946307ms","start":"2026-04-17T00:30:38.265959Z","end":"2026-04-17T00:30:38.989905Z","steps":["trace[1219179469] 'agreement among raft nodes before linearized reading' (duration: 723.765604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.989851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.288862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T00:30:38.989979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.265947Z","time spent":"724.017888ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T00:30:38.990022Z","caller":"traceutil/trace.go:171","msg":"trace[225098081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"737.490515ms","start":"2026-04-17T00:30:38.252515Z","end":"2026-04-17T00:30:38.990005Z","steps":["trace[225098081] 'agreement among raft nodes before linearized reading' (duration: 737.239061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:38.989961Z","caller":"traceutil/trace.go:171","msg":"trace[44950444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"726.498495ms","start":"2026-04-17T00:30:38.263451Z","end":"2026-04-17T00:30:38.989919Z","steps":["trace[44950444] 'agreement among raft nodes before linearized reading' (duration: 726.217401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:38.99021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.263439Z","time spent":"726.758059ms","remote":"127.0.0.1:39008","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-04-17T00:30:38.990089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:38.252496Z","time spent":"737.575786ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T00:30:52.918624Z","caller":"traceutil/trace.go:171","msg":"trace[2066537875] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"122.320118ms","start":"2026-04-17T00:30:52.796268Z","end":"2026-04-17T00:30:52.918588Z","steps":["trace[2066537875] 'process raft request' (duration: 122.133865ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:21.713688Z","caller":"traceutil/trace.go:171","msg":"trace[1153570004] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1373; }","duration":"205.613623ms","start":"2026-04-17T00:32:21.508054Z","end":"2026-04-17T00:32:21.713668Z","steps":["trace[1153570004] 'read index received' (duration: 110.326257ms)","trace[1153570004] 'applied index is now lower than readState.Index' (duration: 95.286746ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:21.713721Z","caller":"traceutil/trace.go:171","msg":"trace[342624732] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"207.98084ms","start":"2026-04-17T00:32:21.505703Z","end":"2026-04-17T00:32:21.713684Z","steps":["trace[342624732] 'process raft request' (duration: 112.567543ms)","trace[342624732] 'compare' (duration: 95.215415ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:21.713739Z","caller":"traceutil/trace.go:171","msg":"trace[1739254937] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"206.765886ms","start":"2026-04-17T00:32:21.506935Z","end":"2026-04-17T00:32:21.713701Z","steps":["trace[1739254937] 'process raft request' (duration: 206.699655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:21.713899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.821495ms","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-04-17T00:32:21.713939Z","caller":"traceutil/trace.go:171","msg":"trace[1861469359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"205.894156ms","start":"2026-04-17T00:32:21.508034Z","end":"2026-04-17T00:32:21.713928Z","steps":["trace[1861469359] 'agreement among raft nodes before linearized reading' (duration: 205.749074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:21.714126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.537935ms","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-04-17T00:32:21.714175Z","caller":"traceutil/trace.go:171","msg":"trace[1225031980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"199.620356ms","start":"2026-04-17T00:32:21.514542Z","end":"2026-04-17T00:32:21.714163Z","steps":["trace[1225031980] 'agreement among raft nodes before linearized reading' (duration: 199.472093ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:22.046076Z","caller":"traceutil/trace.go:171","msg":"trace[1164785150] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"211.174435ms","start":"2026-04-17T00:32:21.834865Z","end":"2026-04-17T00:32:22.046039Z","steps":["trace[1164785150] 'process raft request' (duration: 172.527895ms)","trace[1164785150] 'compare' (duration: 37.900582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:31.998648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.896664ms","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-04-17T00:32:31.998732Z","caller":"traceutil/trace.go:171","msg":"trace[111156825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"236.021635ms","start":"2026-04-17T00:32:31.76269Z","end":"2026-04-17T00:32:31.998712Z","steps":["trace[111156825] 'range keys from in-memory index tree' (duration: 235.689852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:31.998656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.922561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T00:32:31.998648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.92047ms","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-04-17T00:32:31.99901Z","caller":"traceutil/trace.go:171","msg":"trace[757825935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"153.280185ms","start":"2026-04-17T00:32:31.845685Z","end":"2026-04-17T00:32:31.998965Z","steps":["trace[757825935] 'range keys from in-memory index tree' (duration: 152.76879ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:31.998908Z","caller":"traceutil/trace.go:171","msg":"trace[946896270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"153.184654ms","start":"2026-04-17T00:32:31.845659Z","end":"2026-04-17T00:32:31.998843Z","steps":["trace[946896270] 'range keys from in-memory index tree' (duration: 152.677539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:31.999375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.738976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T00:32:31.99943Z","caller":"traceutil/trace.go:171","msg":"trace[679548427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"239.677506ms","start":"2026-04-17T00:32:31.75974Z","end":"2026-04-17T00:32:31.999417Z","steps":["trace[679548427] 'range keys from in-memory index tree' (duration: 238.460443ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:32.166146Z","caller":"traceutil/trace.go:171","msg":"trace[1895897023] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"162.734469ms","start":"2026-04-17T00:32:32.003387Z","end":"2026-04-17T00:32:32.166121Z","steps":["trace[1895897023] 'process raft request' (duration: 162.582648ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:32.729695Z","caller":"traceutil/trace.go:171","msg":"trace[1418635543] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"725.707366ms","start":"2026-04-17T00:32:32.003961Z","end":"2026-04-17T00:32:32.729669Z","steps":["trace[1418635543] 'process raft request' (duration: 725.541974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:32.729826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.003932Z","time spent":"725.826438ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:32:32.730169Z","caller":"traceutil/trace.go:171","msg":"trace[1803969817] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"725.044599ms","start":"2026-04-17T00:32:32.005115Z","end":"2026-04-17T00:32:32.73016Z","steps":["trace[1803969817] 'process raft request' (duration: 724.826697ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:32.730151Z","caller":"traceutil/trace.go:171","msg":"trace[866229290] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"726.03318ms","start":"2026-04-17T00:32:32.004085Z","end":"2026-04-17T00:32:32.730118Z","steps":["trace[866229290] 'process raft request' (duration: 725.556234ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:32.730365Z","caller":"traceutil/trace.go:171","msg":"trace[1505379027] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"654.749019ms","start":"2026-04-17T00:32:32.075608Z","end":"2026-04-17T00:32:32.730357Z","steps":["trace[1505379027] 'process raft request' (duration: 654.477896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:32.730392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.71816ms","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-04-17T00:32:32.730495Z","caller":"traceutil/trace.go:171","msg":"trace[1015697290] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1362; }","duration":"454.882162ms","start":"2026-04-17T00:32:32.275596Z","end":"2026-04-17T00:32:32.730478Z","steps":["trace[1015697290] 'agreement among raft nodes before linearized reading' (duration: 454.614069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:32.730691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.275544Z","time spent":"455.064734ms","remote":"127.0.0.1:38954","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":"warn","ts":"2026-04-17T00:32:32.730424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.075572Z","time spent":"654.82815ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:32:32.730165Z","caller":"traceutil/trace.go:171","msg":"trace[147304348] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1415; }","duration":"454.535368ms","start":"2026-04-17T00:32:32.275613Z","end":"2026-04-17T00:32:32.730148Z","steps":["trace[147304348] 'read index received' (duration: 453.913181ms)","trace[147304348] 'applied index is now lower than readState.Index' (duration: 621.007µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:32.730224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.00507Z","time spent":"725.127219ms","remote":"127.0.0.1:39008","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-04-17T00:32:32.730895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.004049Z","time spent":"726.339643ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}