{"level":"warn","ts":"2026-03-20T05:27:24.71932Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:27:24.719401Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-03-20T05:27:24.719461Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:27:24.719469Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-20T05:27:24.719697Z","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-03-20T05:27:24.72033Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-03-20T05:27:24.720401Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-03-20T05:27:24.724378Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.788421ms"} {"level":"info","ts":"2026-03-20T05:27:24.730917Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-03-20T05:27:24.731002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:27:24.731029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:27:24.731041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:27:24.731052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:27:24.731181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-03-20T05:27:24.737504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:27:24.739258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:27:24.740249Z","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-03-20T05:27:24.744186Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:27:24.744749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:27:24.744765Z","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-03-20T05:27:24.744895Z","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-03-20T05:27:24.744925Z","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-03-20T05:27:24.746786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-03-20T05:27:24.747183Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-20T05:27:24.7488Z","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-03-20T05:27:24.749026Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:27:24.749057Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:27:24.74948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-20T05:27:24.749521Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-20T05:27:25.432361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:27:25.432409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:27:25.432424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-03-20T05:27:25.432434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:27:25.43244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-20T05:27:25.432448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:27:25.432454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-20T05:27:25.433801Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:27:25.433823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:27:25.433908Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:27:25.434197Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:27:25.435486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T05:27:25.434232Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:27:25.436842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:27:25.437559Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-03-20T05:27:25.444925Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:27:25.444998Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:27:25.44502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:27:27.886353Z","caller":"traceutil/trace.go:171","msg":"trace[763101363] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"178.254701ms","start":"2026-03-20T05:27:27.708079Z","end":"2026-03-20T05:27:27.886334Z","steps":["trace[763101363] 'read index received' (duration: 178.08454ms)","trace[763101363] 'applied index is now lower than readState.Index' (duration: 168.081µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:27:27.886437Z","caller":"traceutil/trace.go:171","msg":"trace[1487481401] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"179.996625ms","start":"2026-03-20T05:27:27.706407Z","end":"2026-03-20T05:27:27.886404Z","steps":["trace[1487481401] 'process raft request' (duration: 179.804393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:27.886703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.503873ms","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-03-20T05:27:27.88676Z","caller":"traceutil/trace.go:171","msg":"trace[1586692979] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:70; }","duration":"178.713135ms","start":"2026-03-20T05:27:27.708036Z","end":"2026-03-20T05:27:27.886749Z","steps":["trace[1586692979] 'agreement among raft nodes before linearized reading' (duration: 178.418692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:31.197172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.794907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:27:31.197295Z","caller":"traceutil/trace.go:171","msg":"trace[1855386087] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"167.543415ms","start":"2026-03-20T05:27:31.029734Z","end":"2026-03-20T05:27:31.197277Z","steps":["trace[1855386087] 'read index received' (duration: 23.119253ms)","trace[1855386087] 'applied index is now lower than readState.Index' (duration: 144.422712ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:27:31.197387Z","caller":"traceutil/trace.go:171","msg":"trace[1755336952] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"233.135105ms","start":"2026-03-20T05:27:30.964241Z","end":"2026-03-20T05:27:31.197376Z","steps":["trace[1755336952] 'process raft request' (duration: 88.649782ms)","trace[1755336952] 'compare' (duration: 143.650306ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:27:31.197662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.7634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:27:31.197777Z","caller":"traceutil/trace.go:171","msg":"trace[1630994397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:242; }","duration":"156.930461ms","start":"2026-03-20T05:27:31.040816Z","end":"2026-03-20T05:27:31.197747Z","steps":["trace[1630994397] 'agreement among raft nodes before linearized reading' (duration: 156.576598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:31.197665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.959308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:27:31.198301Z","caller":"traceutil/trace.go:171","msg":"trace[481031444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:242; }","duration":"168.616324ms","start":"2026-03-20T05:27:31.02963Z","end":"2026-03-20T05:27:31.198247Z","steps":["trace[481031444] 'agreement among raft nodes before linearized reading' (duration: 167.841358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.816941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.860774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:27:36.817072Z","caller":"traceutil/trace.go:171","msg":"trace[692742001] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"609.737342ms","start":"2026-03-20T05:27:36.207317Z","end":"2026-03-20T05:27:36.817054Z","steps":["trace[692742001] 'read index received' (duration: 103.945831ms)","trace[692742001] 'applied index is now lower than readState.Index' (duration: 505.789641ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:27:36.81713Z","caller":"traceutil/trace.go:171","msg":"trace[1867632688] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"652.019389ms","start":"2026-03-20T05:27:36.165083Z","end":"2026-03-20T05:27:36.817102Z","steps":["trace[1867632688] 'process raft request' (duration: 146.184757ms)","trace[1867632688] 'compare' (duration: 504.501261ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:27:36.817246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.907624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-03-20T05:27:36.817225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.165057Z","time spent":"652.121609ms","remote":"127.0.0.1:34922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:36.817279Z","caller":"traceutil/trace.go:171","msg":"trace[611992895] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:318; }","duration":"609.990665ms","start":"2026-03-20T05:27:36.207278Z","end":"2026-03-20T05:27:36.817269Z","steps":["trace[611992895] 'agreement among raft nodes before linearized reading' (duration: 609.869904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.817268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.424907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-20T05:27:36.817316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.207262Z","time spent":"610.046005ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-03-20T05:27:36.817333Z","caller":"traceutil/trace.go:171","msg":"trace[1325546968] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:318; }","duration":"558.529127ms","start":"2026-03-20T05:27:36.25879Z","end":"2026-03-20T05:27:36.817319Z","steps":["trace[1325546968] 'agreement among raft nodes before linearized reading' (duration: 558.405936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.817363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.258775Z","time spent":"558.580998ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.693292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-20T05:27:36.817455Z","caller":"traceutil/trace.go:171","msg":"trace[1274755949] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:318; }","duration":"360.806112ms","start":"2026-03-20T05:27:36.456633Z","end":"2026-03-20T05:27:36.817439Z","steps":["trace[1274755949] 'agreement among raft nodes before linearized reading' (duration: 360.649101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.817468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.407472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-20T05:27:36.817468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.063662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-20T05:27:36.817494Z","caller":"traceutil/trace.go:171","msg":"trace[1503681970] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:318; }","duration":"460.457682ms","start":"2026-03-20T05:27:36.357029Z","end":"2026-03-20T05:27:36.817486Z","steps":["trace[1503681970] 'agreement among raft nodes before linearized reading' (duration: 460.413231ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:36.817495Z","caller":"traceutil/trace.go:171","msg":"trace[1372328250] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:318; }","duration":"410.128553ms","start":"2026-03-20T05:27:36.40736Z","end":"2026-03-20T05:27:36.817488Z","steps":["trace[1372328250] 'agreement among raft nodes before linearized reading' (duration: 410.071582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.817498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.456612Z","time spent":"360.877594ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.357014Z","time spent":"460.493982ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.407341Z","time spent":"410.169964ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.147197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:27:36.817524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.862921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-03-20T05:27:36.817587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.699486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-03-20T05:27:36.817587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.053611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-20T05:27:36.817588Z","caller":"traceutil/trace.go:171","msg":"trace[1112512013] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:318; }","duration":"374.962052ms","start":"2026-03-20T05:27:36.44261Z","end":"2026-03-20T05:27:36.817572Z","steps":["trace[1112512013] 'agreement among raft nodes before linearized reading' (duration: 374.841531ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:36.817588Z","caller":"traceutil/trace.go:171","msg":"trace[981526619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:318; }","duration":"155.287368ms","start":"2026-03-20T05:27:36.662278Z","end":"2026-03-20T05:27:36.817566Z","steps":["trace[981526619] 'agreement among raft nodes before linearized reading' (duration: 155.159497ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:36.817605Z","caller":"traceutil/trace.go:171","msg":"trace[1211826534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:318; }","duration":"310.746107ms","start":"2026-03-20T05:27:36.506854Z","end":"2026-03-20T05:27:36.8176Z","steps":["trace[1211826534] 'agreement among raft nodes before linearized reading' (duration: 310.702926ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:36.817615Z","caller":"traceutil/trace.go:171","msg":"trace[351616463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:318; }","duration":"511.115113ms","start":"2026-03-20T05:27:36.306493Z","end":"2026-03-20T05:27:36.817609Z","steps":["trace[351616463] 'agreement among raft nodes before linearized reading' (duration: 511.069332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:36.817625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.506838Z","time spent":"310.784137ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.306471Z","time spent":"511.161163ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-20T05:27:36.817632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.442592Z","time spent":"375.032033ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-20T05:27:36.81764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.612337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T05:27:36.817673Z","caller":"traceutil/trace.go:171","msg":"trace[74170564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"117.696958ms","start":"2026-03-20T05:27:36.699967Z","end":"2026-03-20T05:27:36.817664Z","steps":["trace[74170564] 'agreement among raft nodes before linearized reading' (duration: 117.638478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.403061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.532354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:27:37.403723Z","caller":"traceutil/trace.go:171","msg":"trace[2134491751] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"578.061795ms","start":"2026-03-20T05:27:36.825638Z","end":"2026-03-20T05:27:37.4037Z","steps":["trace[2134491751] 'process raft request' (duration: 577.721852ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.403764Z","caller":"traceutil/trace.go:171","msg":"trace[996487423] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"581.692422ms","start":"2026-03-20T05:27:36.822064Z","end":"2026-03-20T05:27:37.403756Z","steps":["trace[996487423] 'read index received' (duration: 425.278171ms)","trace[996487423] 'applied index is now lower than readState.Index' (duration: 156.413151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:27:37.403809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825618Z","time spent":"578.155125ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:37.403812Z","caller":"traceutil/trace.go:171","msg":"trace[1524369568] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"578.609718ms","start":"2026-03-20T05:27:36.825168Z","end":"2026-03-20T05:27:37.403777Z","steps":["trace[1524369568] 'process raft request' (duration: 578.005774ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.403994Z","caller":"traceutil/trace.go:171","msg":"trace[158244549] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"578.637379ms","start":"2026-03-20T05:27:36.825351Z","end":"2026-03-20T05:27:37.403989Z","steps":["trace[158244549] 'process raft request' (duration: 577.926773ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.403717Z","caller":"traceutil/trace.go:171","msg":"trace[607061824] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"582.080366ms","start":"2026-03-20T05:27:36.821614Z","end":"2026-03-20T05:27:37.403694Z","steps":["trace[607061824] 'process raft request' (duration: 425.713375ms)","trace[607061824] 'compare' (duration: 155.394813ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:27:37.404031Z","caller":"traceutil/trace.go:171","msg":"trace[200401130] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"578.088344ms","start":"2026-03-20T05:27:36.825935Z","end":"2026-03-20T05:27:37.404023Z","steps":["trace[200401130] 'process raft request' (duration: 577.49355ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.403991Z","caller":"traceutil/trace.go:171","msg":"trace[1320328383] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"578.128935ms","start":"2026-03-20T05:27:36.825853Z","end":"2026-03-20T05:27:37.403982Z","steps":["trace[1320328383] 'process raft request' (duration: 577.54419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.404082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825921Z","time spent":"578.133485ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:37.404086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.82515Z","time spent":"578.864ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:37.404098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825833Z","time spent":"578.238036ms","remote":"127.0.0.1:34792","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-03-20T05:27:37.404099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.821602Z","time spent":"582.436148ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:37.403949Z","caller":"traceutil/trace.go:171","msg":"trace[2069786448] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"578.351297ms","start":"2026-03-20T05:27:36.825591Z","end":"2026-03-20T05:27:37.403942Z","steps":["trace[2069786448] 'process raft request' (duration: 577.733032ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.404251Z","caller":"traceutil/trace.go:171","msg":"trace[1317854962] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"578.972662ms","start":"2026-03-20T05:27:36.825255Z","end":"2026-03-20T05:27:37.404228Z","steps":["trace[1317854962] 'process raft request' (duration: 577.969874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.404317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825585Z","time spent":"578.700439ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:37.40432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825347Z","time spent":"578.677039ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:37.404442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825251Z","time spent":"579.084953ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:37.404526Z","caller":"traceutil/trace.go:171","msg":"trace[1175060620] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"578.516728ms","start":"2026-03-20T05:27:36.825999Z","end":"2026-03-20T05:27:37.404515Z","steps":["trace[1175060620] 'process raft request' (duration: 577.51293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.404566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.825991Z","time spent":"578.555368ms","remote":"127.0.0.1:34708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:37.404523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.452918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-20T05:27:37.404615Z","caller":"traceutil/trace.go:171","msg":"trace[773172788] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:327; }","duration":"582.551959ms","start":"2026-03-20T05:27:36.822048Z","end":"2026-03-20T05:27:37.4046Z","steps":["trace[773172788] 'agreement among raft nodes before linearized reading' (duration: 581.771943ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.40464Z","caller":"traceutil/trace.go:171","msg":"trace[643582072] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"578.398447ms","start":"2026-03-20T05:27:36.826229Z","end":"2026-03-20T05:27:37.404627Z","steps":["trace[643582072] 'process raft request' (duration: 578.156165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.404661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.822042Z","time spent":"582.606299ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-03-20T05:27:37.404691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.826223Z","time spent":"578.445217ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:37.404735Z","caller":"traceutil/trace.go:171","msg":"trace[1059587088] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"169.927175ms","start":"2026-03-20T05:27:37.234796Z","end":"2026-03-20T05:27:37.404723Z","steps":["trace[1059587088] 'process raft request' (duration: 169.824694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.4047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.446884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-20T05:27:37.404812Z","caller":"traceutil/trace.go:171","msg":"trace[1784579725] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"577.070986ms","start":"2026-03-20T05:27:36.827733Z","end":"2026-03-20T05:27:37.404804Z","steps":["trace[1784579725] 'process raft request' (duration: 576.832254ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:37.404836Z","caller":"traceutil/trace.go:171","msg":"trace[120174341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"172.605995ms","start":"2026-03-20T05:27:37.232205Z","end":"2026-03-20T05:27:37.404823Z","steps":["trace[120174341] 'agreement among raft nodes before linearized reading' (duration: 172.440684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:37.404853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:36.827727Z","time spent":"577.105567ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:37.4049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.840057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-20T05:27:37.404937Z","caller":"traceutil/trace.go:171","msg":"trace[917652815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"168.913287ms","start":"2026-03-20T05:27:37.236014Z","end":"2026-03-20T05:27:37.404927Z","steps":["trace[917652815] 'agreement among raft nodes before linearized reading' (duration: 168.857197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.065102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.653286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:27:38.065306Z","caller":"traceutil/trace.go:171","msg":"trace[916422977] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"654.018318ms","start":"2026-03-20T05:27:37.411266Z","end":"2026-03-20T05:27:38.065284Z","steps":["trace[916422977] 'process raft request' (duration: 653.912497ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:38.065392Z","caller":"traceutil/trace.go:171","msg":"trace[1577865963] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:342; }","duration":"652.604648ms","start":"2026-03-20T05:27:37.412776Z","end":"2026-03-20T05:27:38.065381Z","steps":["trace[1577865963] 'read index received' (duration: 106.653159ms)","trace[1577865963] 'applied index is now lower than readState.Index' (duration: 545.950959ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:27:38.065446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.411256Z","time spent":"654.117649ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:38.065549Z","caller":"traceutil/trace.go:171","msg":"trace[583312271] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"653.353613ms","start":"2026-03-20T05:27:37.41219Z","end":"2026-03-20T05:27:38.065544Z","steps":["trace[583312271] 'process raft request' (duration: 653.019051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.065583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.412183Z","time spent":"653.381963ms","remote":"127.0.0.1:34596","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-03-20T05:27:38.065649Z","caller":"traceutil/trace.go:171","msg":"trace[1187784041] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"654.586623ms","start":"2026-03-20T05:27:37.411049Z","end":"2026-03-20T05:27:38.065636Z","steps":["trace[1187784041] 'process raft request' (duration: 108.345372ms)","trace[1187784041] 'compare' (duration: 545.555836ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:27:38.065709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.411018Z","time spent":"654.657633ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:38.065758Z","caller":"traceutil/trace.go:171","msg":"trace[1575134285] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"653.446594ms","start":"2026-03-20T05:27:37.412307Z","end":"2026-03-20T05:27:38.065753Z","steps":["trace[1575134285] 'process raft request' (duration: 652.92773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.065785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.4123Z","time spent":"653.470734ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:38.065872Z","caller":"traceutil/trace.go:171","msg":"trace[285648697] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"653.185932ms","start":"2026-03-20T05:27:37.41268Z","end":"2026-03-20T05:27:38.065866Z","steps":["trace[285648697] 'process raft request' (duration: 652.585127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.065893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.412673Z","time spent":"653.209302ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:38.065942Z","caller":"traceutil/trace.go:171","msg":"trace[1830355227] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"653.087751ms","start":"2026-03-20T05:27:37.412851Z","end":"2026-03-20T05:27:38.065939Z","steps":["trace[1830355227] 'process raft request' (duration: 652.435636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.065964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.412845Z","time spent":"653.108381ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:38.066136Z","caller":"traceutil/trace.go:171","msg":"trace[532151662] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"652.89587ms","start":"2026-03-20T05:27:37.413229Z","end":"2026-03-20T05:27:38.066125Z","steps":["trace[532151662] 'process raft request' (duration: 652.076033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.066193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.413224Z","time spent":"652.93964ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:38.066239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.462854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-20T05:27:38.066265Z","caller":"traceutil/trace.go:171","msg":"trace[563028382] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:339; }","duration":"653.495065ms","start":"2026-03-20T05:27:37.412756Z","end":"2026-03-20T05:27:38.066251Z","steps":["trace[563028382] 'agreement among raft nodes before linearized reading' (duration: 653.463224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.06628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.412751Z","time spent":"653.525655ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-03-20T05:27:38.06634Z","caller":"traceutil/trace.go:171","msg":"trace[962173634] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"652.041783ms","start":"2026-03-20T05:27:37.414293Z","end":"2026-03-20T05:27:38.066335Z","steps":["trace[962173634] 'process raft request' (duration: 651.033416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.067419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.276437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:27:38.067633Z","caller":"traceutil/trace.go:171","msg":"trace[1433689301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:339; }","duration":"403.515007ms","start":"2026-03-20T05:27:37.664044Z","end":"2026-03-20T05:27:38.067559Z","steps":["trace[1433689301] 'agreement among raft nodes before linearized reading' (duration: 402.258707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.067665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.663983Z","time spent":"403.668357ms","remote":"127.0.0.1:34362","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-03-20T05:27:38.069588Z","caller":"traceutil/trace.go:171","msg":"trace[1070652590] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"655.042826ms","start":"2026-03-20T05:27:37.414514Z","end":"2026-03-20T05:27:38.069557Z","steps":["trace[1070652590] 'process raft request' (duration: 650.846114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.069748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.414509Z","time spent":"655.167607ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:38.07019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:37.414287Z","time spent":"655.880142ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:38.636002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101837779779122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:27:38.805101Z","caller":"traceutil/trace.go:171","msg":"trace[1704587716] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"729.241823ms","start":"2026-03-20T05:27:38.07583Z","end":"2026-03-20T05:27:38.805072Z","steps":["trace[1704587716] 'process raft request' (duration: 723.860602ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:38.805207Z","caller":"traceutil/trace.go:171","msg":"trace[35051317] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"728.308716ms","start":"2026-03-20T05:27:38.076871Z","end":"2026-03-20T05:27:38.80518Z","steps":["trace[35051317] 'process raft request' (duration: 728.182395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.805369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.075815Z","time spent":"729.368814ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:38.805408Z","caller":"traceutil/trace.go:171","msg":"trace[1910029081] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"728.73223ms","start":"2026-03-20T05:27:38.076653Z","end":"2026-03-20T05:27:38.805385Z","steps":["trace[1910029081] 'process raft request' (duration: 728.315266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.805433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.076864Z","time spent":"728.466967ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:38.805486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.076636Z","time spent":"728.81274ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:27:38.805704Z","caller":"traceutil/trace.go:171","msg":"trace[955160656] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"728.572729ms","start":"2026-03-20T05:27:38.077095Z","end":"2026-03-20T05:27:38.805667Z","steps":["trace[955160656] 'process raft request' (duration: 727.996314ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:38.805787Z","caller":"traceutil/trace.go:171","msg":"trace[1660668489] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"728.046285ms","start":"2026-03-20T05:27:38.077729Z","end":"2026-03-20T05:27:38.805775Z","steps":["trace[1660668489] 'process raft request' (duration: 727.39622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.805833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.07709Z","time spent":"728.67827ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:38.80586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.077723Z","time spent":"728.093385ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:38.805892Z","caller":"traceutil/trace.go:171","msg":"trace[1374330910] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"727.44364ms","start":"2026-03-20T05:27:38.078427Z","end":"2026-03-20T05:27:38.805871Z","steps":["trace[1374330910] 'process raft request' (duration: 727.152908ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:38.805872Z","caller":"traceutil/trace.go:171","msg":"trace[509948118] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"725.563175ms","start":"2026-03-20T05:27:38.080271Z","end":"2026-03-20T05:27:38.805835Z","steps":["trace[509948118] 'process raft request' (duration: 725.497145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.80598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.078419Z","time spent":"727.513331ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:27:38.806103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.080266Z","time spent":"725.703327ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:38.808017Z","caller":"traceutil/trace.go:171","msg":"trace[1639326902] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:352; }","duration":"672.969804ms","start":"2026-03-20T05:27:38.135029Z","end":"2026-03-20T05:27:38.807999Z","steps":["trace[1639326902] 'read index received' (duration: 664.67402ms)","trace[1639326902] 'applied index is now lower than readState.Index' (duration: 8.295034ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:27:38.808069Z","caller":"traceutil/trace.go:171","msg":"trace[1482914426] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"670.237383ms","start":"2026-03-20T05:27:38.137797Z","end":"2026-03-20T05:27:38.808035Z","steps":["trace[1482914426] 'process raft request' (duration: 670.091512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.808168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.084988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"warn","ts":"2026-03-20T05:27:38.808198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.13778Z","time spent":"670.352614ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:27:38.808252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.069618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-20T05:27:38.808278Z","caller":"traceutil/trace.go:171","msg":"trace[861664497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:348; }","duration":"599.141039ms","start":"2026-03-20T05:27:38.20913Z","end":"2026-03-20T05:27:38.808271Z","steps":["trace[861664497] 'agreement among raft nodes before linearized reading' (duration: 599.096808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.808307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.209109Z","time spent":"599.19133ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-03-20T05:27:38.808203Z","caller":"traceutil/trace.go:171","msg":"trace[1674155352] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:348; }","duration":"731.144108ms","start":"2026-03-20T05:27:38.077048Z","end":"2026-03-20T05:27:38.808192Z","steps":["trace[1674155352] 'agreement among raft nodes before linearized reading' (duration: 731.052467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:38.808391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:38.077042Z","time spent":"731.338659ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2862,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-03-20T05:27:38.808641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.966903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-20T05:27:38.808672Z","caller":"traceutil/trace.go:171","msg":"trace[1183065801] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:348; }","duration":"202.027055ms","start":"2026-03-20T05:27:38.606637Z","end":"2026-03-20T05:27:38.808664Z","steps":["trace[1183065801] 'agreement among raft nodes before linearized reading' (duration: 201.954274ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:27:55.855159Z","caller":"traceutil/trace.go:171","msg":"trace[2059403647] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"356.975763ms","start":"2026-03-20T05:27:55.498164Z","end":"2026-03-20T05:27:55.85514Z","steps":["trace[2059403647] 'process raft request' (duration: 356.871738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:27:55.855301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:27:55.498145Z","time spent":"357.094619ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:27:58.860737Z","caller":"traceutil/trace.go:171","msg":"trace[789068079] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"182.265014ms","start":"2026-03-20T05:27:58.678456Z","end":"2026-03-20T05:27:58.860721Z","steps":["trace[789068079] 'process raft request' (duration: 181.366841ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:28:02.218944Z","caller":"traceutil/trace.go:171","msg":"trace[1416518818] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"341.309324ms","start":"2026-03-20T05:28:01.877612Z","end":"2026-03-20T05:28:02.218922Z","steps":["trace[1416518818] 'read index received' (duration: 341.114515ms)","trace[1416518818] 'applied index is now lower than readState.Index' (duration: 193.899µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:28:02.219047Z","caller":"traceutil/trace.go:171","msg":"trace[179872275] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"533.488743ms","start":"2026-03-20T05:28:01.685527Z","end":"2026-03-20T05:28:02.219016Z","steps":["trace[179872275] 'process raft request' (duration: 533.250392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:02.219113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.489851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T05:28:02.219193Z","caller":"traceutil/trace.go:171","msg":"trace[2116570379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"341.611078ms","start":"2026-03-20T05:28:01.877551Z","end":"2026-03-20T05:28:02.219162Z","steps":["trace[2116570379] 'agreement among raft nodes before linearized reading' (duration: 341.498293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:02.219198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:01.68551Z","time spent":"533.611069ms","remote":"127.0.0.1:34596","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-03-20T05:28:02.219238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:01.877527Z","time spent":"341.701572ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:28:02.369833Z","caller":"traceutil/trace.go:171","msg":"trace[1951927117] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"146.208668ms","start":"2026-03-20T05:28:02.223601Z","end":"2026-03-20T05:28:02.36981Z","steps":["trace[1951927117] 'process raft request' (duration: 86.039389ms)","trace[1951927117] 'compare' (duration: 60.067934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:28:03.160379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101837779779451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:28:03.293158Z","caller":"traceutil/trace.go:171","msg":"trace[24965603] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"633.193662ms","start":"2026-03-20T05:28:02.659948Z","end":"2026-03-20T05:28:03.293141Z","steps":["trace[24965603] 'read index received' (duration: 633.000704ms)","trace[24965603] 'applied index is now lower than readState.Index' (duration: 192.358µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:28:03.293257Z","caller":"traceutil/trace.go:171","msg":"trace[226807562] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.069567414s","start":"2026-03-20T05:28:02.223645Z","end":"2026-03-20T05:28:03.293212Z","steps":["trace[226807562] 'process raft request' (duration: 1.069349464s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:28:03.293347Z","caller":"traceutil/trace.go:171","msg":"trace[1899915611] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.069171846s","start":"2026-03-20T05:28:02.224122Z","end":"2026-03-20T05:28:03.293294Z","steps":["trace[1899915611] 'process raft request' (duration: 1.068995348s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:03.294108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:02.224094Z","time spent":"1.069896718s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:28:03.293424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:02.223627Z","time spent":"1.06971117s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:28:03.293478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.930304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T05:28:03.294384Z","caller":"traceutil/trace.go:171","msg":"trace[690518791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"364.877927ms","start":"2026-03-20T05:28:02.929489Z","end":"2026-03-20T05:28:03.294367Z","steps":["trace[690518791] 'agreement among raft nodes before linearized reading' (duration: 363.932895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:03.294428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:02.929468Z","time spent":"364.95035ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:28:03.293512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.571039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:28:03.29454Z","caller":"traceutil/trace.go:171","msg":"trace[754718268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"634.615075ms","start":"2026-03-20T05:28:02.659892Z","end":"2026-03-20T05:28:03.294507Z","steps":["trace[754718268] 'agreement among raft nodes before linearized reading' (duration: 633.575699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:03.294642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:02.65987Z","time spent":"634.73699ms","remote":"127.0.0.1:34362","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-03-20T05:28:03.908231Z","caller":"traceutil/trace.go:171","msg":"trace[171329445] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"246.792934ms","start":"2026-03-20T05:28:03.661417Z","end":"2026-03-20T05:28:03.90821Z","steps":["trace[171329445] 'read index received' (duration: 230.995613ms)","trace[171329445] 'applied index is now lower than readState.Index' (duration: 15.796351ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:28:03.90825Z","caller":"traceutil/trace.go:171","msg":"trace[225928636] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"610.772582ms","start":"2026-03-20T05:28:03.297453Z","end":"2026-03-20T05:28:03.908225Z","steps":["trace[225928636] 'process raft request' (duration: 594.94655ms)","trace[225928636] 'compare' (duration: 15.494978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:28:03.908366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:03.297437Z","time spent":"610.864387ms","remote":"127.0.0.1:34698","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-03-20T05:28:03.909003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.563117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:28:03.909076Z","caller":"traceutil/trace.go:171","msg":"trace[730789145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"247.672272ms","start":"2026-03-20T05:28:03.661382Z","end":"2026-03-20T05:28:03.909055Z","steps":["trace[730789145] 'agreement among raft nodes before linearized reading' (duration: 246.907818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:05.159676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101837779779461,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:28:05.279456Z","caller":"traceutil/trace.go:171","msg":"trace[37376306] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"902.760768ms","start":"2026-03-20T05:28:04.376623Z","end":"2026-03-20T05:28:05.279384Z","steps":["trace[37376306] 'process raft request' (duration: 902.535038ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:28:05.279292Z","caller":"traceutil/trace.go:171","msg":"trace[344546660] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"620.030044ms","start":"2026-03-20T05:28:04.659245Z","end":"2026-03-20T05:28:05.279275Z","steps":["trace[344546660] 'read index received' (duration: 619.848846ms)","trace[344546660] 'applied index is now lower than readState.Index' (duration: 180.468µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:28:05.279561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.298015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:28:05.279583Z","caller":"traceutil/trace.go:171","msg":"trace[1152504438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"620.364758ms","start":"2026-03-20T05:28:04.65921Z","end":"2026-03-20T05:28:05.279574Z","steps":["trace[1152504438] 'agreement among raft nodes before linearized reading' (duration: 620.308225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:05.27958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:04.376608Z","time spent":"902.908334ms","remote":"127.0.0.1:34698","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-03-20T05:28:05.2796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:04.659193Z","time spent":"620.40349ms","remote":"127.0.0.1:34376","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-03-20T05:28:06.162703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101837779779465,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:28:06.415746Z","caller":"traceutil/trace.go:171","msg":"trace[1356821845] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.114438079s","start":"2026-03-20T05:28:05.301279Z","end":"2026-03-20T05:28:06.415718Z","steps":["trace[1356821845] 'process raft request' (duration: 1.053962177s)","trace[1356821845] 'compare' (duration: 60.264353ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:28:06.415895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:05.301259Z","time spent":"1.114560145s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:28:06.428048Z","caller":"traceutil/trace.go:171","msg":"trace[1459911376] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"765.909128ms","start":"2026-03-20T05:28:05.66212Z","end":"2026-03-20T05:28:06.428029Z","steps":["trace[1459911376] 'read index received' (duration: 693.227353ms)","trace[1459911376] 'applied index is now lower than readState.Index' (duration: 72.680265ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:28:06.428108Z","caller":"traceutil/trace.go:171","msg":"trace[185630418] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.126777998s","start":"2026-03-20T05:28:05.301302Z","end":"2026-03-20T05:28:06.42808Z","steps":["trace[185630418] 'process raft request' (duration: 1.126615901s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:06.428202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.063714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:28:06.428233Z","caller":"traceutil/trace.go:171","msg":"trace[1927498509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"766.132777ms","start":"2026-03-20T05:28:05.66209Z","end":"2026-03-20T05:28:06.428223Z","steps":["trace[1927498509] 'agreement among raft nodes before linearized reading' (duration: 766.037473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:06.428276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:05.662077Z","time spent":"766.19234ms","remote":"127.0.0.1:34362","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-03-20T05:28:06.428277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:05.301283Z","time spent":"1.126904764s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:28:06.428381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.210935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T05:28:06.428453Z","caller":"traceutil/trace.go:171","msg":"trace[1494349408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"516.301489ms","start":"2026-03-20T05:28:05.912135Z","end":"2026-03-20T05:28:06.428436Z","steps":["trace[1494349408] 'agreement among raft nodes before linearized reading' (duration: 516.08241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:28:06.428506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:28:05.912064Z","time spent":"516.433855ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:28:08.210782Z","caller":"traceutil/trace.go:171","msg":"trace[705194045] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"246.388372ms","start":"2026-03-20T05:28:07.964375Z","end":"2026-03-20T05:28:08.210763Z","steps":["trace[705194045] 'process raft request' (duration: 246.243946ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:28:08.21506Z","caller":"traceutil/trace.go:171","msg":"trace[52464719] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"208.530477ms","start":"2026-03-20T05:28:08.006516Z","end":"2026-03-20T05:28:08.215046Z","steps":["trace[52464719] 'process raft request' (duration: 208.380651ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:24.51427Z","caller":"traceutil/trace.go:171","msg":"trace[11866562] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"380.086512ms","start":"2026-03-20T05:29:24.134157Z","end":"2026-03-20T05:29:24.514243Z","steps":["trace[11866562] 'process raft request' (duration: 379.883387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:24.514445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:24.134124Z","time spent":"380.229915ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:29:33.0716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.217976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T05:29:33.071702Z","caller":"traceutil/trace.go:171","msg":"trace[583963656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"247.366988ms","start":"2026-03-20T05:29:32.824315Z","end":"2026-03-20T05:29:33.071682Z","steps":["trace[583963656] 'range keys from in-memory index tree' (duration: 247.040893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:33.071621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.093184ms","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-03-20T05:29:33.07188Z","caller":"traceutil/trace.go:171","msg":"trace[1656773480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"247.361819ms","start":"2026-03-20T05:29:32.824488Z","end":"2026-03-20T05:29:33.07185Z","steps":["trace[1656773480] 'range keys from in-memory index tree' (duration: 246.991541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:33.071621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.938011ms","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-03-20T05:29:33.072189Z","caller":"traceutil/trace.go:171","msg":"trace[1252707366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"247.530352ms","start":"2026-03-20T05:29:32.824643Z","end":"2026-03-20T05:29:33.072174Z","steps":["trace[1252707366] 'range keys from in-memory index tree' (duration: 246.831248ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:33.445607Z","caller":"traceutil/trace.go:171","msg":"trace[837328516] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"369.537911ms","start":"2026-03-20T05:29:33.076046Z","end":"2026-03-20T05:29:33.445584Z","steps":["trace[837328516] 'process raft request' (duration: 369.418899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:33.446204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:33.076037Z","time spent":"370.061691ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:29:33.445613Z","caller":"traceutil/trace.go:171","msg":"trace[261941757] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"369.689114ms","start":"2026-03-20T05:29:33.075889Z","end":"2026-03-20T05:29:33.445578Z","steps":["trace[261941757] 'process raft request' (duration: 290.69568ms)","trace[261941757] 'compare' (duration: 78.606306ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:33.446806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:33.075862Z","time spent":"370.904767ms","remote":"127.0.0.1:34698","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-03-20T05:29:33.445745Z","caller":"traceutil/trace.go:171","msg":"trace[1227393489] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"325.917252ms","start":"2026-03-20T05:29:33.119806Z","end":"2026-03-20T05:29:33.445723Z","steps":["trace[1227393489] 'process raft request' (duration: 325.730158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:33.448831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:33.119782Z","time spent":"328.82819ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:29:33.722053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.796332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:29:33.722135Z","caller":"traceutil/trace.go:171","msg":"trace[2106106798] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:956; }","duration":"176.923074ms","start":"2026-03-20T05:29:33.54519Z","end":"2026-03-20T05:29:33.722113Z","steps":["trace[2106106798] 'range keys from in-memory index tree' (duration: 176.702709ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:34.776537Z","caller":"traceutil/trace.go:171","msg":"trace[662701181] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"149.249745ms","start":"2026-03-20T05:29:34.627271Z","end":"2026-03-20T05:29:34.776521Z","steps":["trace[662701181] 'read index received' (duration: 149.091752ms)","trace[662701181] 'applied index is now lower than readState.Index' (duration: 157.433µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:34.776633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.342737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:29:34.776654Z","caller":"traceutil/trace.go:171","msg":"trace[367584666] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:959; }","duration":"149.421148ms","start":"2026-03-20T05:29:34.627225Z","end":"2026-03-20T05:29:34.776646Z","steps":["trace[367584666] 'agreement among raft nodes before linearized reading' (duration: 149.358517ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:34.776615Z","caller":"traceutil/trace.go:171","msg":"trace[1108498706] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"215.342953ms","start":"2026-03-20T05:29:34.561229Z","end":"2026-03-20T05:29:34.776572Z","steps":["trace[1108498706] 'process raft request' (duration: 215.1815ms)"],"step_count":1}