{"level":"warn","ts":"2026-04-07T12:51:11.418497Z","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-07T12:51:11.418625Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-07T12:51:11.41874Z","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-07T12:51:11.418761Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-07T12:51:11.418812Z","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-07T12:51:11.419658Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-04-07T12:51:11.41982Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-07T12:51:11.424911Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.747787ms"} {"level":"info","ts":"2026-04-07T12:51:11.433184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-04-07T12:51:11.433254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:51:11.433276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:51:11.433291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:51:11.433333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:51:11.433372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-04-07T12:51:11.440696Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:51:11.443866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:51:11.445105Z","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-07T12:51:11.447163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:51:11.447291Z","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-07T12:51:11.447393Z","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-07T12:51:11.447418Z","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-07T12:51:11.447781Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:51:11.450131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-04-07T12:51:11.451018Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-07T12:51:11.451946Z","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-07T12:51:11.452272Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-07T12:51:11.452325Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-07T12:51:11.453119Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:51:11.45319Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:51:11.934055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:51:11.934117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:51:11.93415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-04-07T12:51:11.93417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:51:11.9342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-07T12:51:11.934229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:51:11.934258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-07T12:51:11.935471Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:11.937019Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:11.937117Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:11.937157Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:11.93722Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:51:11.937265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:51:11.937916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:51:11.941372Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:51:11.94142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:51:11.942384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-04-07T12:51:11.943144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:51:36.329061Z","caller":"traceutil/trace.go:171","msg":"trace[14214831] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"618.927212ms","start":"2026-04-07T12:51:35.710118Z","end":"2026-04-07T12:51:36.329045Z","steps":["trace[14214831] 'process raft request' (duration: 618.758821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:36.32942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:35.7101Z","time spent":"619.020792ms","remote":"127.0.0.1:33088","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-07T12:51:38.900635Z","caller":"traceutil/trace.go:171","msg":"trace[1435550593] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"146.33845ms","start":"2026-04-07T12:51:38.75423Z","end":"2026-04-07T12:51:38.900569Z","steps":["trace[1435550593] 'process raft request' (duration: 114.175509ms)","trace[1435550593] 'compare' (duration: 31.919929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:40.15783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.149737ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T12:51:41.167981Z","caller":"traceutil/trace.go:171","msg":"trace[1171777747] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"534.718266ms","start":"2026-04-07T12:51:40.633174Z","end":"2026-04-07T12:51:41.167892Z","steps":["trace[1171777747] 'process raft request' (duration: 534.552755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.168452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.633142Z","time spent":"535.22608ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.174496Z","caller":"traceutil/trace.go:171","msg":"trace[428014160] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"505.511581ms","start":"2026-04-07T12:51:40.668967Z","end":"2026-04-07T12:51:41.174479Z","steps":["trace[428014160] 'process raft request' (duration: 505.35197ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:41.174542Z","caller":"traceutil/trace.go:171","msg":"trace[966220673] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"495.42888ms","start":"2026-04-07T12:51:40.679099Z","end":"2026-04-07T12:51:41.174528Z","steps":["trace[966220673] 'process raft request' (duration: 495.247419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.174676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.679069Z","time spent":"495.533541ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.174806Z","caller":"traceutil/trace.go:171","msg":"trace[932956617] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"509.790575ms","start":"2026-04-07T12:51:40.665007Z","end":"2026-04-07T12:51:41.174797Z","steps":["trace[932956617] 'process raft request' (duration: 509.239931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.174745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.668946Z","time spent":"505.607772ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:41.174856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.664985Z","time spent":"509.850177ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.174902Z","caller":"traceutil/trace.go:171","msg":"trace[111758432] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:480; }","duration":"198.906604ms","start":"2026-04-07T12:51:40.975986Z","end":"2026-04-07T12:51:41.174893Z","steps":["trace[111758432] 'read index received' (duration: 191.803248ms)","trace[111758432] 'applied index is now lower than readState.Index' (duration: 7.102876ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:41.174938Z","caller":"traceutil/trace.go:171","msg":"trace[1607879077] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"463.305252ms","start":"2026-04-07T12:51:40.711624Z","end":"2026-04-07T12:51:41.174929Z","steps":["trace[1607879077] 'process raft request' (duration: 462.935309ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:41.174971Z","caller":"traceutil/trace.go:171","msg":"trace[51769969] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"450.676651ms","start":"2026-04-07T12:51:40.724289Z","end":"2026-04-07T12:51:41.174966Z","steps":["trace[51769969] 'process raft request' (duration: 450.395159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.174988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.711592Z","time spent":"463.376372ms","remote":"127.0.0.1:32854","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-07T12:51:41.175009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.724258Z","time spent":"450.731181ms","remote":"127.0.0.1:32854","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-07T12:51:41.17505Z","caller":"traceutil/trace.go:171","msg":"trace[1205956391] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"340.366352ms","start":"2026-04-07T12:51:40.83461Z","end":"2026-04-07T12:51:41.174977Z","steps":["trace[1205956391] 'process raft request' (duration: 340.195381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.175147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.175436ms","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-07T12:51:41.175173Z","caller":"traceutil/trace.go:171","msg":"trace[1948550748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"199.238567ms","start":"2026-04-07T12:51:40.975928Z","end":"2026-04-07T12:51:41.175167Z","steps":["trace[1948550748] 'agreement among raft nodes before linearized reading' (duration: 199.130256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.175162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.834565Z","time spent":"340.522524ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.175243Z","caller":"traceutil/trace.go:171","msg":"trace[982774041] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"482.580637ms","start":"2026-04-07T12:51:40.692638Z","end":"2026-04-07T12:51:41.175219Z","steps":["trace[982774041] 'process raft request' (duration: 481.846261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:41.175338Z","caller":"traceutil/trace.go:171","msg":"trace[871143961] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"496.244507ms","start":"2026-04-07T12:51:40.679088Z","end":"2026-04-07T12:51:41.175332Z","steps":["trace[871143961] 'process raft request' (duration: 495.288939ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:41.175367Z","caller":"traceutil/trace.go:171","msg":"trace[1668932274] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"492.593608ms","start":"2026-04-07T12:51:40.682768Z","end":"2026-04-07T12:51:41.175361Z","steps":["trace[1668932274] 'process raft request' (duration: 491.641241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.175379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.679068Z","time spent":"496.294237ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:41.17534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.692618Z","time spent":"482.674837ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:41.175436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.682758Z","time spent":"492.661338ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.174952Z","caller":"traceutil/trace.go:171","msg":"trace[1281947174] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"450.731641ms","start":"2026-04-07T12:51:40.724214Z","end":"2026-04-07T12:51:41.174946Z","steps":["trace[1281947174] 'process raft request' (duration: 450.379728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.175473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.724198Z","time spent":"451.263865ms","remote":"127.0.0.1:32854","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-07T12:51:41.174394Z","caller":"traceutil/trace.go:171","msg":"trace[1914631900] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"513.740017ms","start":"2026-04-07T12:51:40.66062Z","end":"2026-04-07T12:51:41.17436Z","steps":["trace[1914631900] 'process raft request' (duration: 513.585226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.175594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:40.660592Z","time spent":"514.982206ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:41.647446Z","caller":"traceutil/trace.go:171","msg":"trace[2036809510] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"321.471954ms","start":"2026-04-07T12:51:41.325955Z","end":"2026-04-07T12:51:41.647427Z","steps":["trace[2036809510] 'read index received' (duration: 313.645171ms)","trace[2036809510] 'applied index is now lower than readState.Index' (duration: 7.824353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:41.647551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.584895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:41.647569Z","caller":"traceutil/trace.go:171","msg":"trace[922609222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"321.650176ms","start":"2026-04-07T12:51:41.325913Z","end":"2026-04-07T12:51:41.647563Z","steps":["trace[922609222] 'agreement among raft nodes before linearized reading' (duration: 321.596636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.647588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:41.325897Z","time spent":"321.687256ms","remote":"127.0.0.1:32802","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-07T12:51:41.647577Z","caller":"traceutil/trace.go:171","msg":"trace[909687366] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"338.627602ms","start":"2026-04-07T12:51:41.308899Z","end":"2026-04-07T12:51:41.647527Z","steps":["trace[909687366] 'process raft request' (duration: 330.748478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.647749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:41.308881Z","time spent":"338.777033ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:51:41.64814Z","caller":"traceutil/trace.go:171","msg":"trace[583276508] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"313.054227ms","start":"2026-04-07T12:51:41.335058Z","end":"2026-04-07T12:51:41.648113Z","steps":["trace[583276508] 'process raft request' (duration: 312.783065ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:41.648205Z","caller":"traceutil/trace.go:171","msg":"trace[35283529] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"286.092121ms","start":"2026-04-07T12:51:41.362091Z","end":"2026-04-07T12:51:41.648183Z","steps":["trace[35283529] 'process raft request' (duration: 285.902529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:41.648261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:41.335033Z","time spent":"313.153448ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:41.648198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.001738ms","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-07T12:51:41.648343Z","caller":"traceutil/trace.go:171","msg":"trace[2035521533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"222.198499ms","start":"2026-04-07T12:51:41.426126Z","end":"2026-04-07T12:51:41.648324Z","steps":["trace[2035521533] 'agreement among raft nodes before linearized reading' (duration: 221.974227ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:44.822817Z","caller":"traceutil/trace.go:171","msg":"trace[771620427] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"497.156354ms","start":"2026-04-07T12:51:44.325637Z","end":"2026-04-07T12:51:44.822793Z","steps":["trace[771620427] 'read index received' (duration: 496.947063ms)","trace[771620427] 'applied index is now lower than readState.Index' (duration: 208.461µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:44.823286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.627769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:44.823382Z","caller":"traceutil/trace.go:171","msg":"trace[71909133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"497.774519ms","start":"2026-04-07T12:51:44.325588Z","end":"2026-04-07T12:51:44.823363Z","steps":["trace[71909133] 'agreement among raft nodes before linearized reading' (duration: 497.553627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:44.823432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:44.32557Z","time spent":"497.85096ms","remote":"127.0.0.1:32808","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-07T12:51:44.823313Z","caller":"traceutil/trace.go:171","msg":"trace[1986292873] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"812.646136ms","start":"2026-04-07T12:51:44.010625Z","end":"2026-04-07T12:51:44.823271Z","steps":["trace[1986292873] 'process raft request' (duration: 812.02824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:44.823607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.207437ms","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-07T12:51:44.824043Z","caller":"traceutil/trace.go:171","msg":"trace[1133731332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"458.719241ms","start":"2026-04-07T12:51:44.365305Z","end":"2026-04-07T12:51:44.824024Z","steps":["trace[1133731332] 'agreement among raft nodes before linearized reading' (duration: 458.008946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:44.824093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:44.365287Z","time spent":"458.790572ms","remote":"127.0.0.1:33088","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-07T12:51:44.823981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:44.010594Z","time spent":"813.311761ms","remote":"127.0.0.1:32932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:51:44.967611Z","caller":"traceutil/trace.go:171","msg":"trace[138035363] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"138.998327ms","start":"2026-04-07T12:51:44.828585Z","end":"2026-04-07T12:51:44.967583Z","steps":["trace[138035363] 'process raft request' (duration: 120.048568ms)","trace[138035363] 'compare' (duration: 18.856269ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:44.967916Z","caller":"traceutil/trace.go:171","msg":"trace[1748165931] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"138.580363ms","start":"2026-04-07T12:51:44.829325Z","end":"2026-04-07T12:51:44.967905Z","steps":["trace[1748165931] 'process raft request' (duration: 138.408922ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:44.968019Z","caller":"traceutil/trace.go:171","msg":"trace[382537891] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"130.163697ms","start":"2026-04-07T12:51:44.837845Z","end":"2026-04-07T12:51:44.968008Z","steps":["trace[382537891] 'process raft request' (duration: 129.954565ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:48.904804Z","caller":"traceutil/trace.go:171","msg":"trace[1148982131] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"115.309225ms","start":"2026-04-07T12:51:48.78947Z","end":"2026-04-07T12:51:48.90478Z","steps":["trace[1148982131] 'read index received' (duration: 114.540718ms)","trace[1148982131] 'applied index is now lower than readState.Index' (duration: 767.567µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:48.90488Z","caller":"traceutil/trace.go:171","msg":"trace[489426643] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"234.786721ms","start":"2026-04-07T12:51:48.670057Z","end":"2026-04-07T12:51:48.904844Z","steps":["trace[489426643] 'process raft request' (duration: 234.200106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:48.90502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.535426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z4hp5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T12:51:48.905074Z","caller":"traceutil/trace.go:171","msg":"trace[1145106899] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z4hp5; range_end:; response_count:1; response_revision:577; }","duration":"115.643258ms","start":"2026-04-07T12:51:48.789417Z","end":"2026-04-07T12:51:48.90506Z","steps":["trace[1145106899] 'agreement among raft nodes before linearized reading' (duration: 115.456967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:49.239087Z","caller":"traceutil/trace.go:171","msg":"trace[715395950] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"320.996892ms","start":"2026-04-07T12:51:48.918075Z","end":"2026-04-07T12:51:49.239071Z","steps":["trace[715395950] 'process raft request' (duration: 320.874481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:49.239197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:48.918053Z","time spent":"321.086024ms","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:49.639225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.742273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:51:49.639383Z","caller":"traceutil/trace.go:171","msg":"trace[2125216741] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"650.565168ms","start":"2026-04-07T12:51:48.988741Z","end":"2026-04-07T12:51:49.639306Z","steps":["trace[2125216741] 'read index received' (duration: 250.220895ms)","trace[2125216741] 'applied index is now lower than readState.Index' (duration: 400.342953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:49.639716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.80573ms","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-07T12:51:49.639801Z","caller":"traceutil/trace.go:171","msg":"trace[221286462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"403.938142ms","start":"2026-04-07T12:51:49.235843Z","end":"2026-04-07T12:51:49.639781Z","steps":["trace[221286462] 'agreement among raft nodes before linearized reading' (duration: 403.74858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:49.639844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.235821Z","time spent":"404.014132ms","remote":"127.0.0.1:33088","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-07T12:51:49.63987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.101432ms","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-07T12:51:49.639998Z","caller":"traceutil/trace.go:171","msg":"trace[1993467467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"651.268983ms","start":"2026-04-07T12:51:48.988704Z","end":"2026-04-07T12:51:49.639973Z","steps":["trace[1993467467] 'agreement among raft nodes before linearized reading' (duration: 650.984121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:49.640048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.078599ms","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-07T12:51:49.640057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:48.988689Z","time spent":"651.357574ms","remote":"127.0.0.1:33088","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-07T12:51:49.640083Z","caller":"traceutil/trace.go:171","msg":"trace[1805877221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"388.14488ms","start":"2026-04-07T12:51:49.251926Z","end":"2026-04-07T12:51:49.640071Z","steps":["trace[1805877221] 'agreement among raft nodes before linearized reading' (duration: 388.088419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:49.640112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.251911Z","time spent":"388.19498ms","remote":"127.0.0.1:33088","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-07T12:51:49.639982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.822275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:49.640185Z","caller":"traceutil/trace.go:171","msg":"trace[1130765546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"314.059506ms","start":"2026-04-07T12:51:49.326112Z","end":"2026-04-07T12:51:49.640172Z","steps":["trace[1130765546] 'agreement among raft nodes before linearized reading' (duration: 313.790445ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:49.640205Z","caller":"traceutil/trace.go:171","msg":"trace[79966331] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"660.789387ms","start":"2026-04-07T12:51:48.979404Z","end":"2026-04-07T12:51:49.640194Z","steps":["trace[79966331] 'process raft request' (duration: 310.006596ms)","trace[79966331] 'compare' (duration: 349.497151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:49.640271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:48.979382Z","time spent":"660.846227ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:49.641132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.326097Z","time spent":"314.126407ms","remote":"127.0.0.1:32808","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-07T12:51:50.600654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.686958ms","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-07T12:51:50.601801Z","caller":"traceutil/trace.go:171","msg":"trace[842221600] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"954.737909ms","start":"2026-04-07T12:51:49.647036Z","end":"2026-04-07T12:51:50.601774Z","steps":["trace[842221600] 'process raft request' (duration: 954.416407ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.601854Z","caller":"traceutil/trace.go:171","msg":"trace[22487661] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"178.912687ms","start":"2026-04-07T12:51:50.422932Z","end":"2026-04-07T12:51:50.601845Z","steps":["trace[22487661] 'range keys from in-memory index tree' (duration: 177.668348ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.601548Z","caller":"traceutil/trace.go:171","msg":"trace[1061340764] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"956.212591ms","start":"2026-04-07T12:51:49.64531Z","end":"2026-04-07T12:51:50.601523Z","steps":["trace[1061340764] 'read index received' (duration: 58.928536ms)","trace[1061340764] 'applied index is now lower than readState.Index' (duration: 897.282325ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:50.601761Z","caller":"traceutil/trace.go:171","msg":"trace[1987546954] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"956.830805ms","start":"2026-04-07T12:51:49.644909Z","end":"2026-04-07T12:51:50.60174Z","steps":["trace[1987546954] 'process raft request' (duration: 956.346132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:50.601984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.64702Z","time spent":"954.85108ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:50.602007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.681234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T12:51:50.602062Z","caller":"traceutil/trace.go:171","msg":"trace[1646324632] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:582; }","duration":"956.763804ms","start":"2026-04-07T12:51:49.645286Z","end":"2026-04-07T12:51:50.60205Z","steps":["trace[1646324632] 'agreement among raft nodes before linearized reading' (duration: 956.301851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:50.602062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.644894Z","time spent":"957.116407ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:50.602097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.645276Z","time spent":"956.812045ms","remote":"127.0.0.1:33024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-07T12:51:50.916806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.253274ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T12:51:50.916908Z","caller":"traceutil/trace.go:171","msg":"trace[1646491439] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"314.900684ms","start":"2026-04-07T12:51:50.60199Z","end":"2026-04-07T12:51:50.916891Z","steps":["trace[1646491439] 'read index received' (duration: 170.935286ms)","trace[1646491439] 'applied index is now lower than readState.Index' (duration: 143.963888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:50.917013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.185105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:50.917047Z","caller":"traceutil/trace.go:171","msg":"trace[2077716133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"589.251956ms","start":"2026-04-07T12:51:50.327786Z","end":"2026-04-07T12:51:50.917038Z","steps":["trace[2077716133] 'agreement among raft nodes before linearized reading' (duration: 589.185406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:50.917083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:50.32777Z","time spent":"589.306097ms","remote":"127.0.0.1:32808","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-07T12:51:50.917308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.627921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T12:51:50.917438Z","caller":"traceutil/trace.go:171","msg":"trace[850807192] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:582; }","duration":"306.795212ms","start":"2026-04-07T12:51:50.610623Z","end":"2026-04-07T12:51:50.917418Z","steps":["trace[850807192] 'agreement among raft nodes before linearized reading' (duration: 306.393699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:50.917518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221665812s","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-07T12:51:50.91766Z","caller":"traceutil/trace.go:171","msg":"trace[2039023228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.221887934s","start":"2026-04-07T12:51:49.695742Z","end":"2026-04-07T12:51:50.91763Z","steps":["trace[2039023228] 'agreement among raft nodes before linearized reading' (duration: 1.22134876s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:50.917554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:50.61061Z","time spent":"306.927403ms","remote":"127.0.0.1:33024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-07T12:51:50.917827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:49.695718Z","time spent":"1.222052665s","remote":"127.0.0.1:33088","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-07T12:52:03.920402Z","caller":"traceutil/trace.go:171","msg":"trace[1028163235] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"144.972894ms","start":"2026-04-07T12:52:03.775406Z","end":"2026-04-07T12:52:03.920379Z","steps":["trace[1028163235] 'process raft request' (duration: 144.809936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:07.024502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.455589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:07.024587Z","caller":"traceutil/trace.go:171","msg":"trace[1667023287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"685.588485ms","start":"2026-04-07T12:52:06.338978Z","end":"2026-04-07T12:52:07.024567Z","steps":["trace[1667023287] 'range keys from in-memory index tree' (duration: 684.739649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:07.024625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:06.338954Z","time spent":"685.663398ms","remote":"127.0.0.1:32808","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-07T12:52:07.025631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.643476ms","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-07T12:52:07.025686Z","caller":"traceutil/trace.go:171","msg":"trace[959083559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"345.703149ms","start":"2026-04-07T12:52:06.679967Z","end":"2026-04-07T12:52:07.02567Z","steps":["trace[959083559] 'range keys from in-memory index tree' (duration: 345.574083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:07.025723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:06.679962Z","time spent":"345.753251ms","remote":"127.0.0.1:33088","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-07T12:52:07.025813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.25355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T12:52:07.024612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.738392ms","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-07T12:52:07.0259Z","caller":"traceutil/trace.go:171","msg":"trace[2040052062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"295.387865ms","start":"2026-04-07T12:52:06.73049Z","end":"2026-04-07T12:52:07.025878Z","steps":["trace[2040052062] 'range keys from in-memory index tree' (duration: 295.00815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:07.025934Z","caller":"traceutil/trace.go:171","msg":"trace[947500911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"347.115999ms","start":"2026-04-07T12:52:06.678801Z","end":"2026-04-07T12:52:07.025917Z","steps":["trace[947500911] 'range keys from in-memory index tree' (duration: 345.547654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:07.02599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:06.676747Z","time spent":"349.228858ms","remote":"127.0.0.1:33088","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-07T12:54:05.924174Z","caller":"traceutil/trace.go:171","msg":"trace[821066331] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"211.932092ms","start":"2026-04-07T12:54:05.712214Z","end":"2026-04-07T12:54:05.924146Z","steps":["trace[821066331] 'read index received' (duration: 211.618427ms)","trace[821066331] 'applied index is now lower than readState.Index' (duration: 312.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:05.924289Z","caller":"traceutil/trace.go:171","msg":"trace[550004146] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"227.161777ms","start":"2026-04-07T12:54:05.697081Z","end":"2026-04-07T12:54:05.924243Z","steps":["trace[550004146] 'process raft request' (duration: 226.853423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:05.924486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.232777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:54:05.924553Z","caller":"traceutil/trace.go:171","msg":"trace[967443829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"212.402309ms","start":"2026-04-07T12:54:05.712139Z","end":"2026-04-07T12:54:05.924541Z","steps":["trace[967443829] 'agreement among raft nodes before linearized reading' (duration: 212.177616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:05.924735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.259477ms","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-07T12:54:05.924785Z","caller":"traceutil/trace.go:171","msg":"trace[1182232111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"212.333508ms","start":"2026-04-07T12:54:05.71244Z","end":"2026-04-07T12:54:05.924773Z","steps":["trace[1182232111] 'agreement among raft nodes before linearized reading' (duration: 212.123765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:05.924919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.522612ms","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-07T12:54:05.925023Z","caller":"traceutil/trace.go:171","msg":"trace[1269353273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"212.707935ms","start":"2026-04-07T12:54:05.712294Z","end":"2026-04-07T12:54:05.925002Z","steps":["trace[1269353273] 'agreement among raft nodes before linearized reading' (duration: 212.125195ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:06.064408Z","caller":"traceutil/trace.go:171","msg":"trace[1339990224] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"121.554736ms","start":"2026-04-07T12:54:05.942819Z","end":"2026-04-07T12:54:06.064374Z","steps":["trace[1339990224] 'process raft request' (duration: 59.696788ms)","trace[1339990224] 'compare' (duration: 61.662675ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:06.064707Z","caller":"traceutil/trace.go:171","msg":"trace[1206970680] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1354; }","duration":"103.693975ms","start":"2026-04-07T12:54:05.960847Z","end":"2026-04-07T12:54:06.064541Z","steps":["trace[1206970680] 'read index received' (duration: 4.521704ms)","trace[1206970680] 'applied index is now lower than readState.Index' (duration: 99.170341ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:06.064724Z","caller":"traceutil/trace.go:171","msg":"trace[655819619] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"121.736237ms","start":"2026-04-07T12:54:05.942956Z","end":"2026-04-07T12:54:06.064692Z","steps":["trace[655819619] 'process raft request' (duration: 121.366991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:06.064936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.062751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:06.064985Z","caller":"traceutil/trace.go:171","msg":"trace[1905603566] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1306; }","duration":"104.193583ms","start":"2026-04-07T12:54:05.960777Z","end":"2026-04-07T12:54:06.064971Z","steps":["trace[1905603566] 'agreement among raft nodes before linearized reading' (duration: 103.99539ms)"],"step_count":1}