{"level":"warn","ts":"2026-04-28T03:20:16.391114Z","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-28T03:20:16.391197Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.127: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.19.213.127:2380","--initial-cluster=instance=https://199.19.213.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.127: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-28T03:20:16.391266Z","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-28T03:20:16.391277Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-04-28T03:20:16.391299Z","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-28T03:20:16.391953Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"]} {"level":"info","ts":"2026-04-28T03:20:16.392027Z","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.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.127: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-28T03:20:16.396292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.057195ms"} {"level":"info","ts":"2026-04-28T03:20:16.404952Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"975f045ff4f5c0fb","cluster-id":"b3df79a2daf9b6ed"} {"level":"info","ts":"2026-04-28T03:20:16.405051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:20:16.405084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 0"} {"level":"info","ts":"2026-04-28T03:20:16.405172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 975f045ff4f5c0fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:20:16.405188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 1"} {"level":"info","ts":"2026-04-28T03:20:16.40525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"warn","ts":"2026-04-28T03:20:16.411964Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:20:16.413487Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:20:16.414907Z","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-28T03:20:16.416732Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"975f045ff4f5c0fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:20:16.417815Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"975f045ff4f5c0fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:20:16.41805Z","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-28T03:20:16.418106Z","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-28T03:20:16.418146Z","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-28T03:20:16.418443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"info","ts":"2026-04-28T03:20:16.423748Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","added-peer-id":"975f045ff4f5c0fb","added-peer-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-04-28T03:20:16.424274Z","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-28T03:20:16.424399Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-04-28T03:20:16.424471Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-04-28T03:20:16.425128Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"975f045ff4f5c0fb","initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:20:16.425233Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:20:17.105686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:20:17.105752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:20:17.105786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgPreVoteResp from 975f045ff4f5c0fb at term 1"} {"level":"info","ts":"2026-04-28T03:20:17.105807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:20:17.105819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgVoteResp from 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-04-28T03:20:17.105835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became leader at term 2"} {"level":"info","ts":"2026-04-28T03:20:17.105861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 975f045ff4f5c0fb elected leader 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-04-28T03:20:17.106829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"975f045ff4f5c0fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.127:2379]}","request-path":"/0/members/975f045ff4f5c0fb/attributes","cluster-id":"b3df79a2daf9b6ed","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:20:17.106837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:20:17.106891Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:20:17.107406Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:20:17.107433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:20:17.111089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:20:17.11206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.127:2379"} {"level":"info","ts":"2026-04-28T03:20:17.112795Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:20:17.118164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:20:17.118252Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:20:17.118276Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:20:19.429596Z","caller":"traceutil/trace.go:171","msg":"trace[446840619] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"121.855769ms","start":"2026-04-28T03:20:19.307718Z","end":"2026-04-28T03:20:19.429574Z","steps":["trace[446840619] 'read index received' (duration: 121.679765ms)","trace[446840619] 'applied index is now lower than readState.Index' (duration: 175.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:19.429695Z","caller":"traceutil/trace.go:171","msg":"trace[202000659] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"124.023508ms","start":"2026-04-28T03:20:19.305638Z","end":"2026-04-28T03:20:19.429661Z","steps":["trace[202000659] 'process raft request' (duration: 123.809593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:19.429865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.119894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:20:19.429974Z","caller":"traceutil/trace.go:171","msg":"trace[209001803] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:115; }","duration":"122.273778ms","start":"2026-04-28T03:20:19.307679Z","end":"2026-04-28T03:20:19.429952Z","steps":["trace[209001803] 'agreement among raft nodes before linearized reading' (duration: 122.031303ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:20.585355Z","caller":"traceutil/trace.go:171","msg":"trace[919066807] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"109.988652ms","start":"2026-04-28T03:20:20.475323Z","end":"2026-04-28T03:20:20.585311Z","steps":["trace[919066807] 'process raft request' (duration: 109.855019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:20.760198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.341002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:20.760495Z","caller":"traceutil/trace.go:171","msg":"trace[2105572056] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"268.211416ms","start":"2026-04-28T03:20:20.492258Z","end":"2026-04-28T03:20:20.76047Z","steps":["trace[2105572056] 'process raft request' (duration: 268.048262ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:20.760737Z","caller":"traceutil/trace.go:171","msg":"trace[1526972324] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"268.349869ms","start":"2026-04-28T03:20:20.492257Z","end":"2026-04-28T03:20:20.760607Z","steps":["trace[1526972324] 'process raft request' (duration: 156.105466ms)","trace[1526972324] 'compare' (duration: 111.210929ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:20.97263Z","caller":"traceutil/trace.go:171","msg":"trace[634731347] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"129.137416ms","start":"2026-04-28T03:20:20.843425Z","end":"2026-04-28T03:20:20.972562Z","steps":["trace[634731347] 'process raft request' (duration: 41.793327ms)","trace[634731347] 'compare' (duration: 86.902119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:23.946403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.101917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:23.946621Z","caller":"traceutil/trace.go:171","msg":"trace[1653610821] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"381.203948ms","start":"2026-04-28T03:20:23.5654Z","end":"2026-04-28T03:20:23.946604Z","steps":["trace[1653610821] 'process raft request' (duration: 62.656612ms)","trace[1653610821] 'compare' (duration: 317.955743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:23.946698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.565377Z","time spent":"381.299801ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:20:23.947216Z","caller":"traceutil/trace.go:171","msg":"trace[962587747] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:337; }","duration":"172.582484ms","start":"2026-04-28T03:20:23.774622Z","end":"2026-04-28T03:20:23.947205Z","steps":["trace[962587747] 'read index received' (duration: 170.108209ms)","trace[962587747] 'applied index is now lower than readState.Index' (duration: 2.473495ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:23.94731Z","caller":"traceutil/trace.go:171","msg":"trace[555508726] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"369.922988ms","start":"2026-04-28T03:20:23.577377Z","end":"2026-04-28T03:20:23.9473Z","steps":["trace[555508726] 'process raft request' (duration: 369.569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:23.947452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.813269ms","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-04-28T03:20:23.947537Z","caller":"traceutil/trace.go:171","msg":"trace[1432276011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:325; }","duration":"172.944022ms","start":"2026-04-28T03:20:23.774573Z","end":"2026-04-28T03:20:23.947517Z","steps":["trace[1432276011] 'agreement among raft nodes before linearized reading' (duration: 172.746348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:23.947934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.57736Z","time spent":"370.033501ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:25.313019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881800142428171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:20:25.813657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881800142428171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:20:26.016712Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23054363s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:20:26.105295Z","caller":"traceutil/trace.go:171","msg":"trace[1722012352] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"1.292493623s","start":"2026-04-28T03:20:24.812774Z","end":"2026-04-28T03:20:26.105268Z","steps":["trace[1722012352] 'read index received' (duration: 1.204115356s)","trace[1722012352] 'applied index is now lower than readState.Index' (duration: 88.376967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:26.105565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292818211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T03:20:26.10562Z","caller":"traceutil/trace.go:171","msg":"trace[1518791144] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"1.292949724s","start":"2026-04-28T03:20:24.812658Z","end":"2026-04-28T03:20:26.105608Z","steps":["trace[1518791144] 'agreement among raft nodes before linearized reading' (duration: 1.292826181s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:26.105618Z","caller":"traceutil/trace.go:171","msg":"trace[1962939289] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.319455899s","start":"2026-04-28T03:20:24.786078Z","end":"2026-04-28T03:20:26.105534Z","steps":["trace[1962939289] 'process raft request' (duration: 1.230873598s)","trace[1962939289] 'compare' (duration: 88.184202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:26.105671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.812632Z","time spent":"1.293022326s","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T03:20:26.10571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.855013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T03:20:26.105805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.786057Z","time spent":"1.319660343s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:26.105841Z","caller":"traceutil/trace.go:171","msg":"trace[1268772578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:336; }","duration":"987.017276ms","start":"2026-04-28T03:20:25.118766Z","end":"2026-04-28T03:20:26.105784Z","steps":["trace[1268772578] 'agreement among raft nodes before linearized reading' (duration: 986.822802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:26.105722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.270333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T03:20:26.105915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:25.118747Z","time spent":"987.156859ms","remote":"127.0.0.1:60138","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-28T03:20:26.10592Z","caller":"traceutil/trace.go:171","msg":"trace[799835534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"145.512447ms","start":"2026-04-28T03:20:25.96039Z","end":"2026-04-28T03:20:26.105903Z","steps":["trace[799835534] 'agreement among raft nodes before linearized reading' (duration: 145.256711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:27.1801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.038591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:27.180272Z","caller":"traceutil/trace.go:171","msg":"trace[856740185] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:351; }","duration":"394.576438ms","start":"2026-04-28T03:20:26.785679Z","end":"2026-04-28T03:20:27.180256Z","steps":["trace[856740185] 'read index received' (duration: 29.281µs)","trace[856740185] 'applied index is now lower than readState.Index' (duration: 394.546217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:27.180367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.68956ms","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-28T03:20:27.180403Z","caller":"traceutil/trace.go:171","msg":"trace[2103924441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"394.761792ms","start":"2026-04-28T03:20:26.78563Z","end":"2026-04-28T03:20:27.180392Z","steps":["trace[2103924441] 'agreement among raft nodes before linearized reading' (duration: 394.6813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:27.180445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:26.785612Z","time spent":"394.823443ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T03:20:27.18043Z","caller":"traceutil/trace.go:171","msg":"trace[1257512478] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"1.069152803s","start":"2026-04-28T03:20:26.111248Z","end":"2026-04-28T03:20:27.180401Z","steps":["trace[1257512478] 'process raft request' (duration: 1.068940648s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:27.180512Z","caller":"traceutil/trace.go:171","msg":"trace[724805115] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"1.069290487s","start":"2026-04-28T03:20:26.111142Z","end":"2026-04-28T03:20:27.180432Z","steps":["trace[724805115] 'process raft request' (duration: 577.853917ms)","trace[724805115] 'compare' (duration: 490.840447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:27.180608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:26.111235Z","time spent":"1.069261346s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:27.180772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:26.111132Z","time spent":"1.069536652s","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:20:27.700721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881800142428178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:20:27.757517Z","caller":"traceutil/trace.go:171","msg":"trace[1914872396] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"572.870831ms","start":"2026-04-28T03:20:27.184621Z","end":"2026-04-28T03:20:27.757492Z","steps":["trace[1914872396] 'read index received' (duration: 572.683747ms)","trace[1914872396] 'applied index is now lower than readState.Index' (duration: 185.934µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:27.75757Z","caller":"traceutil/trace.go:171","msg":"trace[1328358495] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"572.987464ms","start":"2026-04-28T03:20:27.184561Z","end":"2026-04-28T03:20:27.757549Z","steps":["trace[1328358495] 'process raft request' (duration: 572.80583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:27.757634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.991084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:27.757667Z","caller":"traceutil/trace.go:171","msg":"trace[1992976677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:340; }","duration":"573.044516ms","start":"2026-04-28T03:20:27.184613Z","end":"2026-04-28T03:20:27.757657Z","steps":["trace[1992976677] 'agreement among raft nodes before linearized reading' (duration: 572.970614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:27.757697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:27.184551Z","time spent":"573.139837ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"warn","ts":"2026-04-28T03:20:27.757697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:27.184543Z","time spent":"573.074236ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:53.444167Z","caller":"traceutil/trace.go:171","msg":"trace[864047721] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"300.15853ms","start":"2026-04-28T03:20:53.143982Z","end":"2026-04-28T03:20:53.444141Z","steps":["trace[864047721] 'process raft request' (duration: 300.041987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:53.444311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:53.143961Z","time spent":"300.288812ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:20:53.451354Z","caller":"traceutil/trace.go:171","msg":"trace[319943648] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"297.712163ms","start":"2026-04-28T03:20:53.153627Z","end":"2026-04-28T03:20:53.451339Z","steps":["trace[319943648] 'process raft request' (duration: 297.418468ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.451513Z","caller":"traceutil/trace.go:171","msg":"trace[1418605371] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"297.785965ms","start":"2026-04-28T03:20:53.153689Z","end":"2026-04-28T03:20:53.451475Z","steps":["trace[1418605371] 'process raft request' (duration: 297.450328ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.451763Z","caller":"traceutil/trace.go:171","msg":"trace[1209434845] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"291.468436ms","start":"2026-04-28T03:20:53.160279Z","end":"2026-04-28T03:20:53.451748Z","steps":["trace[1209434845] 'process raft request' (duration: 291.331763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.45196Z","caller":"traceutil/trace.go:171","msg":"trace[1105642841] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"288.007491ms","start":"2026-04-28T03:20:53.163943Z","end":"2026-04-28T03:20:53.451951Z","steps":["trace[1105642841] 'process raft request' (duration: 287.890559ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.452125Z","caller":"traceutil/trace.go:171","msg":"trace[1684623524] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"287.460261ms","start":"2026-04-28T03:20:53.164655Z","end":"2026-04-28T03:20:53.452116Z","steps":["trace[1684623524] 'process raft request' (duration: 287.247727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.452588Z","caller":"traceutil/trace.go:171","msg":"trace[1454236016] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:548; }","duration":"263.215734ms","start":"2026-04-28T03:20:53.189362Z","end":"2026-04-28T03:20:53.452578Z","steps":["trace[1454236016] 'read index received' (duration: 255.030199ms)","trace[1454236016] 'applied index is now lower than readState.Index' (duration: 8.184655ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:53.45266Z","caller":"traceutil/trace.go:171","msg":"trace[764193398] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"287.42537ms","start":"2026-04-28T03:20:53.165228Z","end":"2026-04-28T03:20:53.452653Z","steps":["trace[764193398] 'process raft request' (duration: 287.081203ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.452764Z","caller":"traceutil/trace.go:171","msg":"trace[751317020] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"284.177809ms","start":"2026-04-28T03:20:53.16858Z","end":"2026-04-28T03:20:53.452757Z","steps":["trace[751317020] 'process raft request' (duration: 283.803402ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.452863Z","caller":"traceutil/trace.go:171","msg":"trace[1279141573] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"284.068567ms","start":"2026-04-28T03:20:53.168788Z","end":"2026-04-28T03:20:53.452856Z","steps":["trace[1279141573] 'process raft request' (duration: 283.627318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.452973Z","caller":"traceutil/trace.go:171","msg":"trace[1100107727] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"278.061424ms","start":"2026-04-28T03:20:53.174906Z","end":"2026-04-28T03:20:53.452967Z","steps":["trace[1100107727] 'process raft request' (duration: 277.543244ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.453082Z","caller":"traceutil/trace.go:171","msg":"trace[1482194364] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"236.787167ms","start":"2026-04-28T03:20:53.216288Z","end":"2026-04-28T03:20:53.453075Z","steps":["trace[1482194364] 'process raft request' (duration: 236.197816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:53.453223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.846935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:53.453333Z","caller":"traceutil/trace.go:171","msg":"trace[1795347517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"263.995089ms","start":"2026-04-28T03:20:53.18933Z","end":"2026-04-28T03:20:53.453325Z","steps":["trace[1795347517] 'agreement among raft nodes before linearized reading' (duration: 263.837136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:53.465217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.480375ms","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-28T03:20:53.465262Z","caller":"traceutil/trace.go:171","msg":"trace[657649837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"156.567027ms","start":"2026-04-28T03:20:53.308685Z","end":"2026-04-28T03:20:53.465252Z","steps":["trace[657649837] 'agreement among raft nodes before linearized reading' (duration: 156.482035ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.931366Z","caller":"traceutil/trace.go:171","msg":"trace[2104068376] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"292.633478ms","start":"2026-04-28T03:20:53.638708Z","end":"2026-04-28T03:20:53.931341Z","steps":["trace[2104068376] 'process raft request' (duration: 276.27165ms)","trace[2104068376] 'compare' (duration: 16.113633ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:53.931785Z","caller":"traceutil/trace.go:171","msg":"trace[1322184660] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"272.427487ms","start":"2026-04-28T03:20:53.659317Z","end":"2026-04-28T03:20:53.931745Z","steps":["trace[1322184660] 'process raft request' (duration: 271.929838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:53.931838Z","caller":"traceutil/trace.go:171","msg":"trace[1516533507] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"260.437862ms","start":"2026-04-28T03:20:53.67139Z","end":"2026-04-28T03:20:53.931828Z","steps":["trace[1516533507] 'process raft request' (duration: 260.241768ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:54.446641Z","caller":"traceutil/trace.go:171","msg":"trace[838236576] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"104.446066ms","start":"2026-04-28T03:20:54.34217Z","end":"2026-04-28T03:20:54.446616Z","steps":["trace[838236576] 'process raft request' (duration: 68.618855ms)","trace[838236576] 'compare' (duration: 35.493875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:55.546089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.394372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:55.546415Z","caller":"traceutil/trace.go:171","msg":"trace[1660823341] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"361.573332ms","start":"2026-04-28T03:20:55.184825Z","end":"2026-04-28T03:20:55.546399Z","steps":["trace[1660823341] 'read index received' (duration: 244.485879ms)","trace[1660823341] 'applied index is now lower than readState.Index' (duration: 117.086573ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:55.54649Z","caller":"traceutil/trace.go:171","msg":"trace[1648817879] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"960.715819ms","start":"2026-04-28T03:20:54.585765Z","end":"2026-04-28T03:20:55.546481Z","steps":["trace[1648817879] 'process raft request' (duration: 960.550316ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:55.546468Z","caller":"traceutil/trace.go:171","msg":"trace[1423102875] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"979.99349ms","start":"2026-04-28T03:20:54.56644Z","end":"2026-04-28T03:20:55.546433Z","steps":["trace[1423102875] 'process raft request' (duration: 979.808156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:55.546554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:54.585742Z","time spent":"960.776359ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:55.546618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:54.566419Z","time spent":"980.130953ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:20:55.546543Z","caller":"traceutil/trace.go:171","msg":"trace[1719262421] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.020025589s","start":"2026-04-28T03:20:54.526488Z","end":"2026-04-28T03:20:55.546513Z","steps":["trace[1719262421] 'process raft request' (duration: 452.117379ms)","trace[1719262421] 'compare' (duration: 567.282259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:55.546686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.855377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:20:55.546689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:54.526466Z","time spent":"1.020198173s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:55.546714Z","caller":"traceutil/trace.go:171","msg":"trace[129855969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"361.933488ms","start":"2026-04-28T03:20:55.184774Z","end":"2026-04-28T03:20:55.546707Z","steps":["trace[129855969] 'agreement among raft nodes before linearized reading' (duration: 361.848216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:55.546734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.184754Z","time spent":"361.976109ms","remote":"127.0.0.1:59896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:20:56.012146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.946782ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T03:20:56.012529Z","caller":"traceutil/trace.go:171","msg":"trace[2020681335] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"466.015318ms","start":"2026-04-28T03:20:55.54645Z","end":"2026-04-28T03:20:56.012466Z","steps":["trace[2020681335] 'read index received' (duration: 47.780641ms)","trace[2020681335] 'applied index is now lower than readState.Index' (duration: 418.233817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:56.012669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.110469ms","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-28T03:20:56.012704Z","caller":"traceutil/trace.go:171","msg":"trace[1710549924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"533.173141ms","start":"2026-04-28T03:20:55.47952Z","end":"2026-04-28T03:20:56.012693Z","steps":["trace[1710549924] 'agreement among raft nodes before linearized reading' (duration: 533.073189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:56.01274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.479507Z","time spent":"533.225231ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T03:20:56.012774Z","caller":"traceutil/trace.go:171","msg":"trace[622174888] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"428.640291ms","start":"2026-04-28T03:20:55.584094Z","end":"2026-04-28T03:20:56.012735Z","steps":["trace[622174888] 'process raft request' (duration: 428.523449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:56.01281Z","caller":"traceutil/trace.go:171","msg":"trace[447434150] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"442.035891ms","start":"2026-04-28T03:20:55.570732Z","end":"2026-04-28T03:20:56.012768Z","steps":["trace[447434150] 'process raft request' (duration: 441.558832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:56.012919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.584077Z","time spent":"428.768293ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:56.012975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.570714Z","time spent":"442.172563ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:56.019966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.848986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:56.020021Z","caller":"traceutil/trace.go:171","msg":"trace[1320444872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"471.938328ms","start":"2026-04-28T03:20:55.54807Z","end":"2026-04-28T03:20:56.020008Z","steps":["trace[1320444872] 'agreement among raft nodes before linearized reading' (duration: 471.845357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:56.020054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.548053Z","time spent":"471.994049ms","remote":"127.0.0.1:59910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:20:56.020379Z","caller":"traceutil/trace.go:171","msg":"trace[1899492262] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"306.276479ms","start":"2026-04-28T03:20:55.714088Z","end":"2026-04-28T03:20:56.020364Z","steps":["trace[1899492262] 'process raft request' (duration: 305.693358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:56.020445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:55.714056Z","time spent":"306.359191ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:21:00.310817Z","caller":"traceutil/trace.go:171","msg":"trace[1070364791] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"228.7054ms","start":"2026-04-28T03:21:00.082089Z","end":"2026-04-28T03:21:00.310795Z","steps":["trace[1070364791] 'read index received' (duration: 228.466976ms)","trace[1070364791] 'applied index is now lower than readState.Index' (duration: 237.584µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:00.310884Z","caller":"traceutil/trace.go:171","msg":"trace[2016168559] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"230.003323ms","start":"2026-04-28T03:21:00.080854Z","end":"2026-04-28T03:21:00.310857Z","steps":["trace[2016168559] 'process raft request' (duration: 229.750099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:00.311047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.926944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T03:21:00.311085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.577347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.127\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:21:00.311107Z","caller":"traceutil/trace.go:171","msg":"trace[491957365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"229.028455ms","start":"2026-04-28T03:21:00.082055Z","end":"2026-04-28T03:21:00.311084Z","steps":["trace[491957365] 'agreement among raft nodes before linearized reading' (duration: 228.859152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:00.311134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.820288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:00.31117Z","caller":"traceutil/trace.go:171","msg":"trace[1565860181] range","detail":"{range_begin:/registry/masterleases/199.19.213.127; range_end:; response_count:1; response_revision:617; }","duration":"200.704449ms","start":"2026-04-28T03:21:00.110447Z","end":"2026-04-28T03:21:00.311152Z","steps":["trace[1565860181] 'agreement among raft nodes before linearized reading' (duration: 200.537776ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:00.311189Z","caller":"traceutil/trace.go:171","msg":"trace[1610191546] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:617; }","duration":"151.90461ms","start":"2026-04-28T03:21:00.159268Z","end":"2026-04-28T03:21:00.311173Z","steps":["trace[1610191546] 'agreement among raft nodes before linearized reading' (duration: 151.809218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:00.814221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881800142428780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:21:01.106087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.801739ms","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-28T03:21:01.106343Z","caller":"traceutil/trace.go:171","msg":"trace[222373338] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:617; }","duration":"379.061304ms","start":"2026-04-28T03:21:00.727257Z","end":"2026-04-28T03:21:01.106318Z","steps":["trace[222373338] 'range keys from in-memory index tree' (duration: 378.785559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.106171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.440513ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-28T03:21:01.106669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.312091Z","time spent":"794.573909ms","remote":"127.0.0.1:59920","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T03:21:01.295663Z","caller":"traceutil/trace.go:171","msg":"trace[924251451] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"980.725919ms","start":"2026-04-28T03:21:00.314925Z","end":"2026-04-28T03:21:01.295651Z","steps":["trace[924251451] 'process raft request' (duration: 980.529256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.295755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.314901Z","time spent":"980.80166ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:01.295764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.8692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:01.295803Z","caller":"traceutil/trace.go:171","msg":"trace[387841179] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:0; response_revision:619; }","duration":"981.936221ms","start":"2026-04-28T03:21:00.313855Z","end":"2026-04-28T03:21:01.295792Z","steps":["trace[387841179] 'agreement among raft nodes before linearized reading' (duration: 981.838279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.295832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.31384Z","time spent":"981.985221ms","remote":"127.0.0.1:59988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" "} {"level":"info","ts":"2026-04-28T03:21:01.295654Z","caller":"traceutil/trace.go:171","msg":"trace[1880828132] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"981.736667ms","start":"2026-04-28T03:21:00.313896Z","end":"2026-04-28T03:21:01.295633Z","steps":["trace[1880828132] 'read index received' (duration: 600.966447ms)","trace[1880828132] 'applied index is now lower than readState.Index' (duration: 380.76942ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:01.295908Z","caller":"traceutil/trace.go:171","msg":"trace[1358557209] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"980.330651ms","start":"2026-04-28T03:21:00.315535Z","end":"2026-04-28T03:21:01.295866Z","steps":["trace[1358557209] 'process raft request' (duration: 980.053156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.296105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.315517Z","time spent":"980.475694ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:01.648791Z","caller":"traceutil/trace.go:171","msg":"trace[1250631780] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"352.794384ms","start":"2026-04-28T03:21:01.295969Z","end":"2026-04-28T03:21:01.648764Z","steps":["trace[1250631780] 'read index received' (duration: 346.669173ms)","trace[1250631780] 'applied index is now lower than readState.Index' (duration: 6.124121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:01.649028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065837602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8lmd9\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-28T03:21:01.648897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.246754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:01.649136Z","caller":"traceutil/trace.go:171","msg":"trace[1770125041] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8lmd9; range_end:; response_count:1; response_revision:620; }","duration":"1.065975254s","start":"2026-04-28T03:21:00.58314Z","end":"2026-04-28T03:21:01.649115Z","steps":["trace[1770125041] 'agreement among raft nodes before linearized reading' (duration: 1.06571994s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:01.649162Z","caller":"traceutil/trace.go:171","msg":"trace[1113424556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"466.551949ms","start":"2026-04-28T03:21:01.182591Z","end":"2026-04-28T03:21:01.649143Z","steps":["trace[1113424556] 'agreement among raft nodes before linearized reading' (duration: 466.217183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.64918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.583128Z","time spent":"1.066042305s","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-8lmd9\" "} {"level":"warn","ts":"2026-04-28T03:21:01.649183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.021252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:21:01.649213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:01.182573Z","time spent":"466.62698ms","remote":"127.0.0.1:59896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:21:01.649229Z","caller":"traceutil/trace.go:171","msg":"trace[616253712] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:0; response_revision:620; }","duration":"351.129394ms","start":"2026-04-28T03:21:01.298084Z","end":"2026-04-28T03:21:01.649214Z","steps":["trace[616253712] 'agreement among raft nodes before linearized reading' (duration: 351.043032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.649268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:01.298066Z","time spent":"351.193015ms","remote":"127.0.0.1:60212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" "} {"level":"info","ts":"2026-04-28T03:21:01.648933Z","caller":"traceutil/trace.go:171","msg":"trace[2134857095] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"541.213094ms","start":"2026-04-28T03:21:01.107686Z","end":"2026-04-28T03:21:01.648899Z","steps":["trace[2134857095] 'process raft request' (duration: 534.93478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.64941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:01.107664Z","time spent":"541.654172ms","remote":"127.0.0.1:59920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:01.649784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189545318s","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-04-28T03:21:01.650098Z","caller":"traceutil/trace.go:171","msg":"trace[1990463450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"1.189882764s","start":"2026-04-28T03:21:00.46019Z","end":"2026-04-28T03:21:01.650073Z","steps":["trace[1990463450] 'agreement among raft nodes before linearized reading' (duration: 1.188698912s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.65027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:00.46017Z","time spent":"1.190013947s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:21:01.964145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.029371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:21:01.964322Z","caller":"traceutil/trace.go:171","msg":"trace[1285882436] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"281.663533ms","start":"2026-04-28T03:21:01.682642Z","end":"2026-04-28T03:21:01.964306Z","steps":["trace[1285882436] 'read index received' (duration: 124.333457ms)","trace[1285882436] 'applied index is now lower than readState.Index' (duration: 157.326106ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:01.964389Z","caller":"traceutil/trace.go:171","msg":"trace[862044771] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"298.263015ms","start":"2026-04-28T03:21:01.666068Z","end":"2026-04-28T03:21:01.964331Z","steps":["trace[862044771] 'process raft request' (duration: 140.971959ms)","trace[862044771] 'compare' (duration: 156.909239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:01.964624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.948458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:01.964717Z","caller":"traceutil/trace.go:171","msg":"trace[1152868092] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:0; response_revision:622; }","duration":"309.06016ms","start":"2026-04-28T03:21:01.655631Z","end":"2026-04-28T03:21:01.964691Z","steps":["trace[1152868092] 'agreement among raft nodes before linearized reading' (duration: 308.823206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:01.964826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:01.655626Z","time spent":"309.187534ms","remote":"127.0.0.1:60194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" "} {"level":"warn","ts":"2026-04-28T03:21:02.819734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881800142428802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:21:03.084354Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09392691s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:21:03.170742Z","caller":"traceutil/trace.go:171","msg":"trace[799469833] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.180369296s","start":"2026-04-28T03:21:01.990343Z","end":"2026-04-28T03:21:03.170712Z","steps":["trace[799469833] 'process raft request' (duration: 1.094179444s)","trace[799469833] 'compare' (duration: 85.694053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:03.170878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:01.990327Z","time spent":"1.180489617s","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:21:03.170995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.773055ms","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-28T03:21:03.171069Z","caller":"traceutil/trace.go:171","msg":"trace[1155802266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"851.876947ms","start":"2026-04-28T03:21:02.319177Z","end":"2026-04-28T03:21:03.171054Z","steps":["trace[1155802266] 'agreement among raft nodes before linearized reading' (duration: 851.651393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:03.171114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:02.319162Z","time spent":"851.939659ms","remote":"127.0.0.1:60138","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-28T03:21:03.171133Z","caller":"traceutil/trace.go:171","msg":"trace[283916521] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"851.903968ms","start":"2026-04-28T03:21:02.319211Z","end":"2026-04-28T03:21:03.171115Z","steps":["trace[283916521] 'read index received' (duration: 765.258198ms)","trace[283916521] 'applied index is now lower than readState.Index' (duration: 86.204772ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:03.751452Z","caller":"traceutil/trace.go:171","msg":"trace[302281888] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"155.679683ms","start":"2026-04-28T03:21:03.595757Z","end":"2026-04-28T03:21:03.751437Z","steps":["trace[302281888] 'process raft request' (duration: 155.54275ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:03.765112Z","caller":"traceutil/trace.go:171","msg":"trace[327416479] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"153.90459ms","start":"2026-04-28T03:21:03.611158Z","end":"2026-04-28T03:21:03.765062Z","steps":["trace[327416479] 'process raft request' (duration: 153.592154ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:03.765591Z","caller":"traceutil/trace.go:171","msg":"trace[1301690868] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"142.879612ms","start":"2026-04-28T03:21:03.622687Z","end":"2026-04-28T03:21:03.765567Z","steps":["trace[1301690868] 'process raft request' (duration: 142.362553ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:05.087545Z","caller":"traceutil/trace.go:171","msg":"trace[566830605] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"370.23357ms","start":"2026-04-28T03:21:04.717162Z","end":"2026-04-28T03:21:05.087396Z","steps":["trace[566830605] 'process raft request' (duration: 370.100828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.087683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.717145Z","time spent":"370.484074ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:21:05.11497Z","caller":"traceutil/trace.go:171","msg":"trace[1850154685] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"338.805505ms","start":"2026-04-28T03:21:04.776126Z","end":"2026-04-28T03:21:05.114931Z","steps":["trace[1850154685] 'process raft request' (duration: 337.840087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.11515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.776105Z","time spent":"338.952427ms","remote":"127.0.0.1:59972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:07.911432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.661415ms","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-04-28T03:21:07.911517Z","caller":"traceutil/trace.go:171","msg":"trace[37812966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"125.825998ms","start":"2026-04-28T03:21:07.785671Z","end":"2026-04-28T03:21:07.911497Z","steps":["trace[37812966] 'range keys from in-memory index tree' (duration: 125.443181ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:16.183012Z","caller":"traceutil/trace.go:171","msg":"trace[288939944] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"228.470923ms","start":"2026-04-28T03:21:15.954518Z","end":"2026-04-28T03:21:16.182989Z","steps":["trace[288939944] 'process raft request' (duration: 228.32987ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:19.66143Z","caller":"traceutil/trace.go:171","msg":"trace[1690462776] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"456.055321ms","start":"2026-04-28T03:21:19.205354Z","end":"2026-04-28T03:21:19.661409Z","steps":["trace[1690462776] 'read index received' (duration: 455.828307ms)","trace[1690462776] 'applied index is now lower than readState.Index' (duration: 226.114µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:19.661584Z","caller":"traceutil/trace.go:171","msg":"trace[671602109] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"971.212101ms","start":"2026-04-28T03:21:18.690333Z","end":"2026-04-28T03:21:19.661545Z","steps":["trace[671602109] 'process raft request' (duration: 970.946196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:19.661748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:18.690318Z","time spent":"971.341333ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:19.661755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.352906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:21:19.661873Z","caller":"traceutil/trace.go:171","msg":"trace[423909473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"456.505488ms","start":"2026-04-28T03:21:19.205322Z","end":"2026-04-28T03:21:19.661827Z","steps":["trace[423909473] 'agreement among raft nodes before linearized reading' (duration: 456.219223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:19.661939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:19.205307Z","time spent":"456.60799ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:21:19.661969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.830763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T03:21:19.661992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.510113ms","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-28T03:21:19.662005Z","caller":"traceutil/trace.go:171","msg":"trace[371789049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"222.885683ms","start":"2026-04-28T03:21:19.439108Z","end":"2026-04-28T03:21:19.661994Z","steps":["trace[371789049] 'agreement among raft nodes before linearized reading' (duration: 222.761081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:19.66203Z","caller":"traceutil/trace.go:171","msg":"trace[1267041400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"220.591275ms","start":"2026-04-28T03:21:19.441427Z","end":"2026-04-28T03:21:19.662019Z","steps":["trace[1267041400] 'agreement among raft nodes before linearized reading' (duration: 220.491883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:19.662107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.516285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:21:19.662133Z","caller":"traceutil/trace.go:171","msg":"trace[1690416545] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:797; }","duration":"288.565236ms","start":"2026-04-28T03:21:19.373559Z","end":"2026-04-28T03:21:19.662124Z","steps":["trace[1690416545] 'agreement among raft nodes before linearized reading' (duration: 288.520065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:19.662114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.622976ms","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-28T03:21:19.662317Z","caller":"traceutil/trace.go:171","msg":"trace[680735219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"369.886991ms","start":"2026-04-28T03:21:19.292398Z","end":"2026-04-28T03:21:19.662284Z","steps":["trace[680735219] 'agreement among raft nodes before linearized reading' (duration: 369.436794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:19.662375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:19.292381Z","time spent":"369.982193ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T03:22:02.339333Z","caller":"traceutil/trace.go:171","msg":"trace[754170402] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"136.033112ms","start":"2026-04-28T03:22:02.203273Z","end":"2026-04-28T03:22:02.339306Z","steps":["trace[754170402] 'process raft request' (duration: 52.000655ms)","trace[754170402] 'compare' (duration: 83.900775ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:02.341542Z","caller":"traceutil/trace.go:171","msg":"trace[1007390238] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"129.476734ms","start":"2026-04-28T03:22:02.212044Z","end":"2026-04-28T03:22:02.34152Z","steps":["trace[1007390238] 'process raft request' (duration: 129.437523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:02.341626Z","caller":"traceutil/trace.go:171","msg":"trace[2129233797] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"136.049072ms","start":"2026-04-28T03:22:02.205552Z","end":"2026-04-28T03:22:02.341602Z","steps":["trace[2129233797] 'process raft request' (duration: 135.810538ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:14.549427Z","caller":"traceutil/trace.go:171","msg":"trace[938197167] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"122.597821ms","start":"2026-04-28T03:22:14.426766Z","end":"2026-04-28T03:22:14.549364Z","steps":["trace[938197167] 'process raft request' (duration: 94.519656ms)","trace[938197167] 'compare' (duration: 27.583667ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:14.550367Z","caller":"traceutil/trace.go:171","msg":"trace[717742036] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"102.748327ms","start":"2026-04-28T03:22:14.447594Z","end":"2026-04-28T03:22:14.550342Z","steps":["trace[717742036] 'process raft request' (duration: 102.454973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:35.126152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.630871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:22:35.126374Z","caller":"traceutil/trace.go:171","msg":"trace[1522415583] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"174.655413ms","start":"2026-04-28T03:22:34.951695Z","end":"2026-04-28T03:22:35.12635Z","steps":["trace[1522415583] 'read index received' (duration: 8.995619ms)","trace[1522415583] 'applied index is now lower than readState.Index' (duration: 165.658214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:35.126466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.767374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:22:35.126511Z","caller":"traceutil/trace.go:171","msg":"trace[148337261] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1192; }","duration":"174.853226ms","start":"2026-04-28T03:22:34.951643Z","end":"2026-04-28T03:22:35.126496Z","steps":["trace[148337261] 'agreement among raft nodes before linearized reading' (duration: 174.777895ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:35.126538Z","caller":"traceutil/trace.go:171","msg":"trace[691321260] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"218.668244ms","start":"2026-04-28T03:22:34.907848Z","end":"2026-04-28T03:22:35.126517Z","steps":["trace[691321260] 'process raft request' (duration: 218.430691ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:35.126566Z","caller":"traceutil/trace.go:171","msg":"trace[1415915743] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"235.370884ms","start":"2026-04-28T03:22:34.891143Z","end":"2026-04-28T03:22:35.126514Z","steps":["trace[1415915743] 'process raft request' (duration: 58.261146ms)","trace[1415915743] 'compare' (duration: 176.469609ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:56.658208Z","caller":"traceutil/trace.go:171","msg":"trace[1956718091] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"103.55313ms","start":"2026-04-28T03:22:56.554626Z","end":"2026-04-28T03:22:56.658179Z","steps":["trace[1956718091] 'process raft request' (duration: 102.434865ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:03.245872Z","caller":"traceutil/trace.go:171","msg":"trace[1267388004] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"155.410832ms","start":"2026-04-28T03:23:03.090435Z","end":"2026-04-28T03:23:03.245846Z","steps":["trace[1267388004] 'read index received' (duration: 155.034347ms)","trace[1267388004] 'applied index is now lower than readState.Index' (duration: 375.585µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:03.245955Z","caller":"traceutil/trace.go:171","msg":"trace[1571468658] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"182.192205ms","start":"2026-04-28T03:23:03.063733Z","end":"2026-04-28T03:23:03.245925Z","steps":["trace[1571468658] 'process raft request' (duration: 181.913541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:03.246117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.673496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:23:03.24616Z","caller":"traceutil/trace.go:171","msg":"trace[1877762580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"155.731387ms","start":"2026-04-28T03:23:03.090418Z","end":"2026-04-28T03:23:03.246149Z","steps":["trace[1877762580] 'agreement among raft nodes before linearized reading' (duration: 155.583795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:11.008625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.142344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:23:11.008736Z","caller":"traceutil/trace.go:171","msg":"trace[722922024] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"273.459116ms","start":"2026-04-28T03:23:10.735257Z","end":"2026-04-28T03:23:11.008717Z","steps":["trace[722922024] 'process raft request' (duration: 36.144ms)","trace[722922024] 'compare' (duration: 237.019712ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:11.062263Z","caller":"traceutil/trace.go:171","msg":"trace[256821520] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"288.368962ms","start":"2026-04-28T03:23:10.773867Z","end":"2026-04-28T03:23:11.062236Z","steps":["trace[256821520] 'process raft request' (duration: 288.22871ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:11.912678Z","caller":"traceutil/trace.go:171","msg":"trace[223409912] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"577.049084ms","start":"2026-04-28T03:23:11.335603Z","end":"2026-04-28T03:23:11.912652Z","steps":["trace[223409912] 'process raft request' (duration: 576.849441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:11.912819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:11.335572Z","time spent":"577.173356ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:23:11.998972Z","caller":"traceutil/trace.go:171","msg":"trace[1883764845] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1402; }","duration":"214.122543ms","start":"2026-04-28T03:23:11.784827Z","end":"2026-04-28T03:23:11.998949Z","steps":["trace[1883764845] 'read index received' (duration: 127.673847ms)","trace[1883764845] 'applied index is now lower than readState.Index' (duration: 86.447716ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:11.999114Z","caller":"traceutil/trace.go:171","msg":"trace[2070059139] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"663.081275ms","start":"2026-04-28T03:23:11.336006Z","end":"2026-04-28T03:23:11.999087Z","steps":["trace[2070059139] 'process raft request' (duration: 644.468227ms)","trace[2070059139] 'compare' (duration: 18.331674ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:11.999331Z","caller":"traceutil/trace.go:171","msg":"trace[246025866] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"663.216697ms","start":"2026-04-28T03:23:11.336096Z","end":"2026-04-28T03:23:11.999313Z","steps":["trace[246025866] 'process raft request' (duration: 662.810561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:11.999465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:11.335987Z","time spent":"663.360559ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:23:11.999575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:11.336077Z","time spent":"663.41679ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:23:11.999229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.378977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:23:11.999713Z","caller":"traceutil/trace.go:171","msg":"trace[1848782930] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1348; }","duration":"214.906583ms","start":"2026-04-28T03:23:11.784792Z","end":"2026-04-28T03:23:11.999698Z","steps":["trace[1848782930] 'agreement among raft nodes before linearized reading' (duration: 214.348586ms)"],"step_count":1}