{"level":"warn","ts":"2026-07-09T15:26:54.620605Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T15:26:54.620687Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.118: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.118:2380","--initial-cluster=instance=https://199.19.213.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.118: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-07-09T15:26:54.620743Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T15:26:54.62075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-07-09T15:26:54.620774Z","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-07-09T15:26:54.621498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"]} {"level":"info","ts":"2026-07-09T15:26:54.62157Z","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.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.118: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-07-09T15:26:54.636203Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.403796ms"} {"level":"info","ts":"2026-07-09T15:26:54.647348Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"511d8c552db01fa8","cluster-id":"8d545d29b73b98f3"} {"level":"info","ts":"2026-07-09T15:26:54.647442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T15:26:54.647471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 0"} {"level":"info","ts":"2026-07-09T15:26:54.647485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 511d8c552db01fa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T15:26:54.647496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 1"} {"level":"info","ts":"2026-07-09T15:26:54.647531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"warn","ts":"2026-07-09T15:26:54.654397Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T15:26:54.656903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T15:26:54.658653Z","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-07-09T15:26:54.661616Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"511d8c552db01fa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T15:26:54.662686Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"511d8c552db01fa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T15:26:54.662898Z","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-07-09T15:26:54.663001Z","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-07-09T15:26:54.663052Z","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-07-09T15:26:54.672303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"info","ts":"2026-07-09T15:26:54.672445Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","added-peer-id":"511d8c552db01fa8","added-peer-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-07-09T15:26:54.673461Z","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-07-09T15:26:54.673603Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-07-09T15:26:54.673641Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-07-09T15:26:54.673701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"511d8c552db01fa8","initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T15:26:54.673749Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T15:26:55.648437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T15:26:55.648519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T15:26:55.64859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgPreVoteResp from 511d8c552db01fa8 at term 1"} {"level":"info","ts":"2026-07-09T15:26:55.648625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became candidate at term 2"} {"level":"info","ts":"2026-07-09T15:26:55.64867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgVoteResp from 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-07-09T15:26:55.64872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became leader at term 2"} {"level":"info","ts":"2026-07-09T15:26:55.648741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 511d8c552db01fa8 elected leader 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-07-09T15:26:55.651533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:26:55.652785Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:26:55.652914Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"511d8c552db01fa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.118:2379]}","request-path":"/0/members/511d8c552db01fa8/attributes","cluster-id":"8d545d29b73b98f3","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T15:26:55.653015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:26:55.653037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:26:55.653053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:26:55.653167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:26:55.654896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.118:2379"} {"level":"info","ts":"2026-07-09T15:26:55.654965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T15:26:55.65498Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T15:26:55.655175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T15:26:58.242682Z","caller":"traceutil/trace.go:171","msg":"trace[113140624] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"266.554138ms","start":"2026-07-09T15:26:57.976111Z","end":"2026-07-09T15:26:58.242665Z","steps":["trace[113140624] 'read index received' (duration: 266.417393ms)","trace[113140624] 'applied index is now lower than readState.Index' (duration: 136.125µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:58.242801Z","caller":"traceutil/trace.go:171","msg":"trace[750419207] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"359.185967ms","start":"2026-07-09T15:26:57.883606Z","end":"2026-07-09T15:26:58.242792Z","steps":["trace[750419207] 'process raft request' (duration: 358.934348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.242966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.838358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:26:58.243072Z","caller":"traceutil/trace.go:171","msg":"trace[528346696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"266.995494ms","start":"2026-07-09T15:26:57.976063Z","end":"2026-07-09T15:26:58.243058Z","steps":["trace[528346696] 'agreement among raft nodes before linearized reading' (duration: 266.818407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.243336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.883596Z","time spent":"359.225649ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:26:59.019387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.550822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T15:26:59.019476Z","caller":"traceutil/trace.go:171","msg":"trace[1833315919] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"694.77434ms","start":"2026-07-09T15:26:58.324689Z","end":"2026-07-09T15:26:59.019464Z","steps":["trace[1833315919] 'compare' (duration: 683.385635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:59.019515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:58.32467Z","time spent":"694.831093ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:26:59.576478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248340654995496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:27:00.570993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.824387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T15:27:00.571215Z","caller":"traceutil/trace.go:171","msg":"trace[355979928] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"1.546021719s","start":"2026-07-09T15:26:59.025145Z","end":"2026-07-09T15:27:00.571167Z","steps":["trace[355979928] 'process raft request' (duration: 758.937873ms)","trace[355979928] 'compare' (duration: 786.677082ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:00.571315Z","caller":"traceutil/trace.go:171","msg":"trace[1562769279] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"1.495506723s","start":"2026-07-09T15:26:59.075766Z","end":"2026-07-09T15:27:00.571273Z","steps":["trace[1562769279] 'read index received' (duration: 708.326563ms)","trace[1562769279] 'applied index is now lower than readState.Index' (duration: 787.17741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:00.571379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:59.025128Z","time spent":"1.546160956s","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:27:00.571512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495746881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:27:00.57155Z","caller":"traceutil/trace.go:171","msg":"trace[99387994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"1.495831016s","start":"2026-07-09T15:26:59.075707Z","end":"2026-07-09T15:27:00.571538Z","steps":["trace[99387994] 'agreement among raft nodes before linearized reading' (duration: 1.495709862s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:00.57158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:59.075685Z","time spent":"1.495887857s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T15:27:00.571879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.711083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:27:00.571917Z","caller":"traceutil/trace.go:171","msg":"trace[1040713803] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"344.628293ms","start":"2026-07-09T15:27:00.227271Z","end":"2026-07-09T15:27:00.5719Z","steps":["trace[1040713803] 'process raft request' (duration: 343.827074ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:00.571947Z","caller":"traceutil/trace.go:171","msg":"trace[1312534307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:119; }","duration":"615.828928ms","start":"2026-07-09T15:26:59.956098Z","end":"2026-07-09T15:27:00.571926Z","steps":["trace[1312534307] 'agreement among raft nodes before linearized reading' (duration: 615.714334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:00.57198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:00.22724Z","time spent":"344.708027ms","remote":"127.0.0.1:36310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:27:00.571985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.284617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T15:27:00.571995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:59.956072Z","time spent":"615.912702ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T15:27:00.572036Z","caller":"traceutil/trace.go:171","msg":"trace[854907784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:119; }","duration":"420.37075ms","start":"2026-07-09T15:27:00.151651Z","end":"2026-07-09T15:27:00.572022Z","steps":["trace[854907784] 'agreement among raft nodes before linearized reading' (duration: 420.295908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:00.57208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:00.151629Z","time spent":"420.440962ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T15:27:00.781073Z","caller":"traceutil/trace.go:171","msg":"trace[2130383602] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:125; }","duration":"135.743386ms","start":"2026-07-09T15:27:00.645305Z","end":"2026-07-09T15:27:00.781049Z","steps":["trace[2130383602] 'read index received' (duration: 115.742102ms)","trace[2130383602] 'applied index is now lower than readState.Index' (duration: 19.999894ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:00.781173Z","caller":"traceutil/trace.go:171","msg":"trace[1993998827] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"204.959252ms","start":"2026-07-09T15:27:00.576147Z","end":"2026-07-09T15:27:00.781106Z","steps":["trace[1993998827] 'process raft request' (duration: 184.889065ms)","trace[1993998827] 'compare' (duration: 19.89195ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:00.781277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.950503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T15:27:00.781348Z","caller":"traceutil/trace.go:171","msg":"trace[1566163367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:121; }","duration":"136.075448ms","start":"2026-07-09T15:27:00.645257Z","end":"2026-07-09T15:27:00.781332Z","steps":["trace[1566163367] 'agreement among raft nodes before linearized reading' (duration: 135.884671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:01.130394Z","caller":"traceutil/trace.go:171","msg":"trace[656149283] linearizableReadLoop","detail":"{readStateIndex:131; appliedIndex:130; }","duration":"102.653834ms","start":"2026-07-09T15:27:01.027705Z","end":"2026-07-09T15:27:01.130358Z","steps":["trace[656149283] 'read index received' (duration: 102.153345ms)","trace[656149283] 'applied index is now lower than readState.Index' (duration: 499.058µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:01.130541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.81645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:27:01.130613Z","caller":"traceutil/trace.go:171","msg":"trace[1090043490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:126; }","duration":"102.933434ms","start":"2026-07-09T15:27:01.027668Z","end":"2026-07-09T15:27:01.130601Z","steps":["trace[1090043490] 'agreement among raft nodes before linearized reading' (duration: 102.791879ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:01.130703Z","caller":"traceutil/trace.go:171","msg":"trace[1450203804] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"261.385396ms","start":"2026-07-09T15:27:00.869276Z","end":"2026-07-09T15:27:01.130661Z","steps":["trace[1450203804] 'process raft request' (duration: 260.592586ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:01.270119Z","caller":"traceutil/trace.go:171","msg":"trace[1022947813] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"133.40101ms","start":"2026-07-09T15:27:01.136692Z","end":"2026-07-09T15:27:01.270093Z","steps":["trace[1022947813] 'process raft request' (duration: 111.3757ms)","trace[1022947813] 'compare' (duration: 20.153883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:02.169635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.412045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:02.169707Z","caller":"traceutil/trace.go:171","msg":"trace[222124173] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"232.893081ms","start":"2026-07-09T15:27:01.936803Z","end":"2026-07-09T15:27:02.169696Z","steps":["trace[222124173] 'process raft request' (duration: 110.369203ms)","trace[222124173] 'compare' (duration: 122.30788ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:02.5026Z","caller":"traceutil/trace.go:171","msg":"trace[2020405443] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"150.852221ms","start":"2026-07-09T15:27:02.351701Z","end":"2026-07-09T15:27:02.502553Z","steps":["trace[2020405443] 'process raft request' (duration: 63.344355ms)","trace[2020405443] 'compare' (duration: 87.385131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:02.762857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.891001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:02.762941Z","caller":"traceutil/trace.go:171","msg":"trace[898913290] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:0; response_revision:161; }","duration":"159.045627ms","start":"2026-07-09T15:27:02.603875Z","end":"2026-07-09T15:27:02.762921Z","steps":["trace[898913290] 'range keys from in-memory index tree' (duration: 158.759686ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:02.932676Z","caller":"traceutil/trace.go:171","msg":"trace[1648552054] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"166.0228ms","start":"2026-07-09T15:27:02.766623Z","end":"2026-07-09T15:27:02.932646Z","steps":["trace[1648552054] 'process raft request' (duration: 165.825192ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:02.936076Z","caller":"traceutil/trace.go:171","msg":"trace[2085470139] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:166; }","duration":"111.182231ms","start":"2026-07-09T15:27:02.82487Z","end":"2026-07-09T15:27:02.936053Z","steps":["trace[2085470139] 'read index received' (duration: 107.664505ms)","trace[2085470139] 'applied index is now lower than readState.Index' (duration: 3.516636ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:02.936165Z","caller":"traceutil/trace.go:171","msg":"trace[1446130023] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"130.104793ms","start":"2026-07-09T15:27:02.806024Z","end":"2026-07-09T15:27:02.936129Z","steps":["trace[1446130023] 'process raft request' (duration: 129.939037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:02.936406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.510372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T15:27:02.936501Z","caller":"traceutil/trace.go:171","msg":"trace[475063292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:163; }","duration":"111.704109ms","start":"2026-07-09T15:27:02.824773Z","end":"2026-07-09T15:27:02.936477Z","steps":["trace[475063292] 'agreement among raft nodes before linearized reading' (duration: 111.365357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:03.136402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.122344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:03.136596Z","caller":"traceutil/trace.go:171","msg":"trace[1052632150] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"196.50069ms","start":"2026-07-09T15:27:02.94007Z","end":"2026-07-09T15:27:03.136571Z","steps":["trace[1052632150] 'process raft request' (duration: 54.680842ms)","trace[1052632150] 'compare' (duration: 140.961457ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:03.137002Z","caller":"traceutil/trace.go:171","msg":"trace[1442811731] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"196.715419ms","start":"2026-07-09T15:27:02.940132Z","end":"2026-07-09T15:27:03.136848Z","steps":["trace[1442811731] 'process raft request' (duration: 196.410128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:05.68851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.192296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-07-09T15:27:05.688633Z","caller":"traceutil/trace.go:171","msg":"trace[1316923976] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:263; }","duration":"235.368633ms","start":"2026-07-09T15:27:05.453238Z","end":"2026-07-09T15:27:05.688606Z","steps":["trace[1316923976] 'range keys from in-memory index tree' (duration: 234.993849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:05.999714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.107668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:05.999851Z","caller":"traceutil/trace.go:171","msg":"trace[1031208] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"235.002851ms","start":"2026-07-09T15:27:05.764825Z","end":"2026-07-09T15:27:05.999828Z","steps":["trace[1031208] 'process raft request' (duration: 134.705166ms)","trace[1031208] 'compare' (duration: 99.945922ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:07.309928Z","caller":"traceutil/trace.go:171","msg":"trace[99847691] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"122.728298ms","start":"2026-07-09T15:27:07.187157Z","end":"2026-07-09T15:27:07.309886Z","steps":["trace[99847691] 'process raft request' (duration: 85.703082ms)","trace[99847691] 'compare' (duration: 36.898591ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:19.354398Z","caller":"traceutil/trace.go:171","msg":"trace[930115255] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"133.447704ms","start":"2026-07-09T15:27:19.220909Z","end":"2026-07-09T15:27:19.354357Z","steps":["trace[930115255] 'read index received' (duration: 38.156174ms)","trace[930115255] 'applied index is now lower than readState.Index' (duration: 95.28954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:19.354831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.89844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T15:27:19.354976Z","caller":"traceutil/trace.go:171","msg":"trace[1816161602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"134.110116ms","start":"2026-07-09T15:27:19.220839Z","end":"2026-07-09T15:27:19.354949Z","steps":["trace[1816161602] 'agreement among raft nodes before linearized reading' (duration: 133.662741ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:25.470108Z","caller":"traceutil/trace.go:171","msg":"trace[427326375] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"128.687052ms","start":"2026-07-09T15:27:25.341378Z","end":"2026-07-09T15:27:25.470065Z","steps":["trace[427326375] 'process raft request' (duration: 128.524977ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:26.10756Z","caller":"traceutil/trace.go:171","msg":"trace[674192804] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"630.322869ms","start":"2026-07-09T15:27:25.47721Z","end":"2026-07-09T15:27:26.107533Z","steps":["trace[674192804] 'process raft request' (duration: 630.253666ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:26.107674Z","caller":"traceutil/trace.go:171","msg":"trace[1386634992] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"632.68808ms","start":"2026-07-09T15:27:25.474966Z","end":"2026-07-09T15:27:26.107655Z","steps":["trace[1386634992] 'process raft request' (duration: 625.382199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:26.107732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:25.477195Z","time spent":"630.461192ms","remote":"127.0.0.1:36600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:27:26.107875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:25.474934Z","time spent":"632.784324ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:39.420078Z","caller":"traceutil/trace.go:171","msg":"trace[2032657966] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"308.898925ms","start":"2026-07-09T15:27:39.111157Z","end":"2026-07-09T15:27:39.420056Z","steps":["trace[2032657966] 'process raft request' (duration: 306.886578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:39.420197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:39.111149Z","time spent":"308.993787ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:27:39.420296Z","caller":"traceutil/trace.go:171","msg":"trace[638709601] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"303.282957ms","start":"2026-07-09T15:27:39.117002Z","end":"2026-07-09T15:27:39.420285Z","steps":["trace[638709601] 'process raft request' (duration: 303.03216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:39.420334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:39.116985Z","time spent":"303.328868ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:27:39.420569Z","caller":"traceutil/trace.go:171","msg":"trace[667918936] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"309.359308ms","start":"2026-07-09T15:27:39.111195Z","end":"2026-07-09T15:27:39.420554Z","steps":["trace[667918936] 'process raft request' (duration: 308.768251ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.420612Z","caller":"traceutil/trace.go:171","msg":"trace[1936255123] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"238.672175ms","start":"2026-07-09T15:27:39.181919Z","end":"2026-07-09T15:27:39.420591Z","steps":["trace[1936255123] 'process raft request' (duration: 238.599583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:39.420656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:39.111191Z","time spent":"309.421219ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:27:39.420791Z","caller":"traceutil/trace.go:171","msg":"trace[1846150128] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"297.465434ms","start":"2026-07-09T15:27:39.12331Z","end":"2026-07-09T15:27:39.420776Z","steps":["trace[1846150128] 'process raft request' (duration: 296.891768ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.420878Z","caller":"traceutil/trace.go:171","msg":"trace[1700086637] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"301.045815ms","start":"2026-07-09T15:27:39.119824Z","end":"2026-07-09T15:27:39.420869Z","steps":["trace[1700086637] 'process raft request' (duration: 300.248412ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.420879Z","caller":"traceutil/trace.go:171","msg":"trace[765350632] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"256.810074ms","start":"2026-07-09T15:27:39.164054Z","end":"2026-07-09T15:27:39.420865Z","steps":["trace[765350632] 'process raft request' (duration: 256.372291ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.42101Z","caller":"traceutil/trace.go:171","msg":"trace[1273481294] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"300.193671ms","start":"2026-07-09T15:27:39.120794Z","end":"2026-07-09T15:27:39.420988Z","steps":["trace[1273481294] 'process raft request' (duration: 299.347917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:39.420962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:39.119796Z","time spent":"301.138097ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:27:39.421091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:39.120781Z","time spent":"300.274083ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:27:39.421067Z","caller":"traceutil/trace.go:171","msg":"trace[591549234] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"268.495341ms","start":"2026-07-09T15:27:39.152498Z","end":"2026-07-09T15:27:39.420994Z","steps":["trace[591549234] 'process raft request' (duration: 267.75156ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.421382Z","caller":"traceutil/trace.go:171","msg":"trace[1969861163] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"262.794291ms","start":"2026-07-09T15:27:39.158138Z","end":"2026-07-09T15:27:39.420932Z","steps":["trace[1969861163] 'process raft request' (duration: 262.194035ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.440299Z","caller":"traceutil/trace.go:171","msg":"trace[1949466581] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"252.234546ms","start":"2026-07-09T15:27:39.188044Z","end":"2026-07-09T15:27:39.440279Z","steps":["trace[1949466581] 'process raft request' (duration: 251.66904ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.440683Z","caller":"traceutil/trace.go:171","msg":"trace[1111492148] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"253.142971ms","start":"2026-07-09T15:27:39.18753Z","end":"2026-07-09T15:27:39.440673Z","steps":["trace[1111492148] 'process raft request' (duration: 252.0488ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.450808Z","caller":"traceutil/trace.go:171","msg":"trace[737488745] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:554; }","duration":"166.000396ms","start":"2026-07-09T15:27:39.284791Z","end":"2026-07-09T15:27:39.450791Z","steps":["trace[737488745] 'read index received' (duration: 133.230667ms)","trace[737488745] 'applied index is now lower than readState.Index' (duration: 32.768789ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:39.45082Z","caller":"traceutil/trace.go:171","msg":"trace[1830606620] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"146.113308ms","start":"2026-07-09T15:27:39.304687Z","end":"2026-07-09T15:27:39.4508Z","steps":["trace[1830606620] 'process raft request' (duration: 145.977774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:39.450997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.193631ms","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-07-09T15:27:39.451023Z","caller":"traceutil/trace.go:171","msg":"trace[1430103929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"166.275303ms","start":"2026-07-09T15:27:39.284741Z","end":"2026-07-09T15:27:39.451017Z","steps":["trace[1430103929] 'agreement among raft nodes before linearized reading' (duration: 166.120049ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:39.99689Z","caller":"traceutil/trace.go:171","msg":"trace[212951162] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"105.024167ms","start":"2026-07-09T15:27:39.891847Z","end":"2026-07-09T15:27:39.996871Z","steps":["trace[212951162] 'process raft request' (duration: 96.828377ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:45.793093Z","caller":"traceutil/trace.go:171","msg":"trace[855007190] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"135.321503ms","start":"2026-07-09T15:27:45.657743Z","end":"2026-07-09T15:27:45.793064Z","steps":["trace[855007190] 'read index received' (duration: 134.45999ms)","trace[855007190] 'applied index is now lower than readState.Index' (duration: 857.953µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:45.793187Z","caller":"traceutil/trace.go:171","msg":"trace[1891066981] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"164.73423ms","start":"2026-07-09T15:27:45.628419Z","end":"2026-07-09T15:27:45.793153Z","steps":["trace[1891066981] 'process raft request' (duration: 164.045491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:45.793276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.508347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:45.793707Z","caller":"traceutil/trace.go:171","msg":"trace[1032117963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"136.00065ms","start":"2026-07-09T15:27:45.657694Z","end":"2026-07-09T15:27:45.793695Z","steps":["trace[1032117963] 'agreement among raft nodes before linearized reading' (duration: 135.518588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:45.793876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.648776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T15:27:45.793923Z","caller":"traceutil/trace.go:171","msg":"trace[1401777553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"109.740868ms","start":"2026-07-09T15:27:45.684172Z","end":"2026-07-09T15:27:45.793913Z","steps":["trace[1401777553] 'agreement among raft nodes before linearized reading' (duration: 109.544714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:45.794024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.864522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vvhkn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-09T15:27:45.794137Z","caller":"traceutil/trace.go:171","msg":"trace[723282538] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vvhkn; range_end:; response_count:1; response_revision:638; }","duration":"127.041547ms","start":"2026-07-09T15:27:45.667074Z","end":"2026-07-09T15:27:45.794115Z","steps":["trace[723282538] 'agreement among raft nodes before linearized reading' (duration: 126.615915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:46.061579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.031917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:46.061849Z","caller":"traceutil/trace.go:171","msg":"trace[1989372892] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"262.855974ms","start":"2026-07-09T15:27:45.798892Z","end":"2026-07-09T15:27:46.061748Z","steps":["trace[1989372892] 'process raft request' (duration: 162.570991ms)","trace[1989372892] 'compare' (duration: 99.902474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:47.137154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.852773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:47.137303Z","caller":"traceutil/trace.go:171","msg":"trace[143323670] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"879.789779ms","start":"2026-07-09T15:27:46.257496Z","end":"2026-07-09T15:27:47.137286Z","steps":["trace[143323670] 'read index received' (duration: 63.626937ms)","trace[143323670] 'applied index is now lower than readState.Index' (duration: 816.161832ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:47.137384Z","caller":"traceutil/trace.go:171","msg":"trace[142116538] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.328093878s","start":"2026-07-09T15:27:45.809281Z","end":"2026-07-09T15:27:47.137375Z","steps":["trace[142116538] 'process raft request' (duration: 511.952837ms)","trace[142116538] 'compare' (duration: 815.645609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:47.137431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:45.809258Z","time spent":"1.32815049s","remote":"127.0.0.1:36310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:47.137575Z","caller":"traceutil/trace.go:171","msg":"trace[761569498] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.284382383s","start":"2026-07-09T15:27:45.853185Z","end":"2026-07-09T15:27:47.137568Z","steps":["trace[761569498] 'process raft request' (duration: 1.284054684s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:47.137609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:45.853163Z","time spent":"1.284428063s","remote":"127.0.0.1:36630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:27:47.137742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.126276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:47.137822Z","caller":"traceutil/trace.go:171","msg":"trace[1017954911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"478.264771ms","start":"2026-07-09T15:27:46.659536Z","end":"2026-07-09T15:27:47.137801Z","steps":["trace[1017954911] 'agreement among raft nodes before linearized reading' (duration: 478.103147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:47.137859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:46.659508Z","time spent":"478.344364ms","remote":"127.0.0.1:36132","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-07-09T15:27:47.137949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.050659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T15:27:47.137952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.458356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T15:27:47.137976Z","caller":"traceutil/trace.go:171","msg":"trace[2050920261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"287.120152ms","start":"2026-07-09T15:27:46.850847Z","end":"2026-07-09T15:27:47.137967Z","steps":["trace[2050920261] 'agreement among raft nodes before linearized reading' (duration: 287.018249ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:47.137985Z","caller":"traceutil/trace.go:171","msg":"trace[236389145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"880.519769ms","start":"2026-07-09T15:27:46.257455Z","end":"2026-07-09T15:27:47.137975Z","steps":["trace[236389145] 'agreement among raft nodes before linearized reading' (duration: 880.403396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:47.138006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:46.257437Z","time spent":"880.56467ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T15:27:50.021214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751931008s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:50.021423Z","caller":"traceutil/trace.go:171","msg":"trace[1897888439] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"2.873562805s","start":"2026-07-09T15:27:47.147846Z","end":"2026-07-09T15:27:50.021409Z","steps":["trace[1897888439] 'read index received' (duration: 121.366299ms)","trace[1897888439] 'applied index is now lower than readState.Index' (duration: 2.752195626s)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:50.021482Z","caller":"traceutil/trace.go:171","msg":"trace[190637903] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.879317189s","start":"2026-07-09T15:27:47.142149Z","end":"2026-07-09T15:27:50.021466Z","steps":["trace[190637903] 'process raft request' (duration: 127.051132ms)","trace[190637903] 'compare' (duration: 2.751408514s)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:50.021493Z","caller":"traceutil/trace.go:171","msg":"trace[151689074] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.879058632s","start":"2026-07-09T15:27:47.142427Z","end":"2026-07-09T15:27:50.021486Z","steps":["trace[151689074] 'process raft request' (duration: 2.878890157s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.021534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:47.142399Z","time spent":"2.879111984s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:27:50.021539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:47.142123Z","time spent":"2.879387311s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:50.021631Z","caller":"traceutil/trace.go:171","msg":"trace[775199826] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.678183297s","start":"2026-07-09T15:27:47.343439Z","end":"2026-07-09T15:27:50.021622Z","steps":["trace[775199826] 'process raft request' (duration: 2.67792522s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.021664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.873815821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:50.021686Z","caller":"traceutil/trace.go:171","msg":"trace[1901937780] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:644; }","duration":"2.873867161s","start":"2026-07-09T15:27:47.14781Z","end":"2026-07-09T15:27:50.021677Z","steps":["trace[1901937780] 'agreement among raft nodes before linearized reading' (duration: 2.873818431s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.021707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:47.147794Z","time spent":"2.873908734s","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-09T15:27:50.021699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:47.343405Z","time spent":"2.678272039s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:50.022094Z","caller":"traceutil/trace.go:171","msg":"trace[434824537] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.825277063s","start":"2026-07-09T15:27:48.196781Z","end":"2026-07-09T15:27:50.022058Z","steps":["trace[434824537] 'process raft request' (duration: 1.82480476s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.022283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:48.196754Z","time spent":"1.825431997s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:27:50.040789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.521143483s","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-07-09T15:27:50.040867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974415251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T15:27:50.040945Z","caller":"traceutil/trace.go:171","msg":"trace[1792807753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.974544005s","start":"2026-07-09T15:27:48.066381Z","end":"2026-07-09T15:27:50.040925Z","steps":["trace[1792807753] 'agreement among raft nodes before linearized reading' (duration: 1.974276709s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.040921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384081352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:50.041046Z","caller":"traceutil/trace.go:171","msg":"trace[1517758485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.384276068s","start":"2026-07-09T15:27:48.656744Z","end":"2026-07-09T15:27:50.04102Z","steps":["trace[1517758485] 'agreement among raft nodes before linearized reading' (duration: 1.383896827s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.040988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:48.066357Z","time spent":"1.974623267s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:27:50.041123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:48.656724Z","time spent":"1.384388212s","remote":"127.0.0.1:36132","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-07-09T15:27:50.040891Z","caller":"traceutil/trace.go:171","msg":"trace[506050122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"2.521318908s","start":"2026-07-09T15:27:47.519552Z","end":"2026-07-09T15:27:50.040871Z","steps":["trace[506050122] 'agreement among raft nodes before linearized reading' (duration: 2.521043801s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:50.041369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:47.519519Z","time spent":"2.52177855s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T15:27:52.133206Z","caller":"traceutil/trace.go:171","msg":"trace[430698652] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"106.418825ms","start":"2026-07-09T15:27:52.026759Z","end":"2026-07-09T15:27:52.133177Z","steps":["trace[430698652] 'read index received' (duration: 106.23611ms)","trace[430698652] 'applied index is now lower than readState.Index' (duration: 181.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:52.13325Z","caller":"traceutil/trace.go:171","msg":"trace[2130583777] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"242.206235ms","start":"2026-07-09T15:27:51.890998Z","end":"2026-07-09T15:27:52.133204Z","steps":["trace[2130583777] 'process raft request' (duration: 242.02401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:52.133608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.828516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T15:27:52.133686Z","caller":"traceutil/trace.go:171","msg":"trace[915067144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"106.97059ms","start":"2026-07-09T15:27:52.026701Z","end":"2026-07-09T15:27:52.133672Z","steps":["trace[915067144] 'agreement among raft nodes before linearized reading' (duration: 106.607481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:52.139209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.152466ms","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-07-09T15:27:52.13929Z","caller":"traceutil/trace.go:171","msg":"trace[1657833248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"112.286808ms","start":"2026-07-09T15:27:52.02698Z","end":"2026-07-09T15:27:52.139266Z","steps":["trace[1657833248] 'agreement among raft nodes before linearized reading' (duration: 111.530248ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:05.376589Z","caller":"traceutil/trace.go:171","msg":"trace[1259504893] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"593.697843ms","start":"2026-07-09T15:28:04.782861Z","end":"2026-07-09T15:28:05.376559Z","steps":["trace[1259504893] 'process raft request' (duration: 593.515849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:05.376885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:04.782849Z","time spent":"593.942539ms","remote":"127.0.0.1:36310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:28:14.876402Z","caller":"traceutil/trace.go:171","msg":"trace[2080681513] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"108.896206ms","start":"2026-07-09T15:28:14.767479Z","end":"2026-07-09T15:28:14.876375Z","steps":["trace[2080681513] 'process raft request' (duration: 80.386859ms)","trace[2080681513] 'compare' (duration: 28.400485ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:29:55.137964Z","caller":"traceutil/trace.go:171","msg":"trace[1775347062] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"142.781587ms","start":"2026-07-09T15:29:54.995139Z","end":"2026-07-09T15:29:55.137921Z","steps":["trace[1775347062] 'process raft request' (duration: 88.058631ms)","trace[1775347062] 'compare' (duration: 54.530012ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:30:01.039116Z","caller":"traceutil/trace.go:171","msg":"trace[1324417693] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"137.433892ms","start":"2026-07-09T15:30:00.901631Z","end":"2026-07-09T15:30:01.039065Z","steps":["trace[1324417693] 'process raft request' (duration: 137.148166ms)"],"step_count":1}