{"level":"warn","ts":"2026-02-25T12:46:53.274357Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T12:46:53.274459Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-02-25T12:46:53.274536Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T12:46:53.274546Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-25T12:46:53.274565Z","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-02-25T12:46:53.275534Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-02-25T12:46:53.27562Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-02-25T12:46:53.281103Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.217273ms"} {"level":"info","ts":"2026-02-25T12:46:53.29887Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-02-25T12:46:53.298929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T12:46:53.298949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-02-25T12:46:53.298958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T12:46:53.29897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-02-25T12:46:53.299006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-02-25T12:46:53.319885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T12:46:53.325692Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T12:46:53.331546Z","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-02-25T12:46:53.335151Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T12:46:53.336563Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T12:46:53.337027Z","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-02-25T12:46:53.337103Z","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-02-25T12:46:53.33738Z","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-02-25T12:46:53.337441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-02-25T12:46:53.339441Z","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-02-25T12:46:53.339568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-25T12:46:53.339635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-25T12:46:53.34008Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T12:46:53.339616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T12:46:53.340559Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-25T12:46:53.899806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T12:46:53.899879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T12:46:53.899904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-02-25T12:46:53.899924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-02-25T12:46:53.899936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-25T12:46:53.899953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-02-25T12:46:53.899967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-25T12:46:53.902256Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T12:46:53.902305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T12:46:53.902533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T12:46:53.902754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T12:46:53.902758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T12:46:53.902809Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T12:46:53.903676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T12:46:53.903857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T12:46:53.904004Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T12:46:53.906405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-02-25T12:46:53.906788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T12:47:03.086976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154736125244894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T12:47:03.587562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154736125244894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T12:47:04.088009Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154736125244894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T12:47:04.322855Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.589236751s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T12:47:04.323229Z","caller":"traceutil/trace.go:171","msg":"trace[1739469693] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"1.736936264s","start":"2026-02-25T12:47:02.586273Z","end":"2026-02-25T12:47:04.323209Z","steps":["trace[1739469693] 'read index received' (duration: 1.736719107s)","trace[1739469693] 'applied index is now lower than readState.Index' (duration: 216.357µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:04.323272Z","caller":"traceutil/trace.go:171","msg":"trace[473996947] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"1.776959761s","start":"2026-02-25T12:47:02.546288Z","end":"2026-02-25T12:47:04.323248Z","steps":["trace[473996947] 'process raft request' (duration: 1.776768816s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686288976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-25T12:47:04.323371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737097777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T12:47:04.323436Z","caller":"traceutil/trace.go:171","msg":"trace[166112569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.686392897s","start":"2026-02-25T12:47:02.637031Z","end":"2026-02-25T12:47:04.323424Z","steps":["trace[166112569] 'agreement among raft nodes before linearized reading' (duration: 1.686299115s)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:04.32344Z","caller":"traceutil/trace.go:171","msg":"trace[688359098] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.737239901s","start":"2026-02-25T12:47:02.58619Z","end":"2026-02-25T12:47:04.32343Z","steps":["trace[688359098] 'agreement among raft nodes before linearized reading' (duration: 1.737077327s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.986705ms","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-02-25T12:47:04.323462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.586148Z","time spent":"1.737308632s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.323471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386560012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T12:47:04.323553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486659987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T12:47:04.323572Z","caller":"traceutil/trace.go:171","msg":"trace[749104240] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.486703169s","start":"2026-02-25T12:47:02.836863Z","end":"2026-02-25T12:47:04.323566Z","steps":["trace[749104240] 'agreement among raft nodes before linearized reading' (duration: 1.486673148s)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:04.323561Z","caller":"traceutil/trace.go:171","msg":"trace[674446928] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.386709345s","start":"2026-02-25T12:47:02.936831Z","end":"2026-02-25T12:47:04.32354Z","steps":["trace[674446928] 'agreement among raft nodes before linearized reading' (duration: 1.38650212s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.32358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.828094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T12:47:04.323594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.836849Z","time spent":"1.486739049s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.323461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.63701Z","time spent":"1.686444859s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-02-25T12:47:04.323614Z","caller":"traceutil/trace.go:171","msg":"trace[1545471954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:302; }","duration":"310.881035ms","start":"2026-02-25T12:47:04.012724Z","end":"2026-02-25T12:47:04.323605Z","steps":["trace[1545471954] 'agreement among raft nodes before linearized reading' (duration: 310.826023ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:04.323512Z","caller":"traceutil/trace.go:171","msg":"trace[2059180057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:302; }","duration":"316.082167ms","start":"2026-02-25T12:47:04.007416Z","end":"2026-02-25T12:47:04.323498Z","steps":["trace[2059180057] 'agreement among raft nodes before linearized reading' (duration: 315.951814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437320712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-25T12:47:04.323647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.007394Z","time spent":"316.24615ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T12:47:04.323648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.012711Z","time spent":"310.929376ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T12:47:04.32367Z","caller":"traceutil/trace.go:171","msg":"trace[407264367] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:302; }","duration":"1.437484075s","start":"2026-02-25T12:47:02.886176Z","end":"2026-02-25T12:47:04.32366Z","steps":["trace[407264367] 'agreement among raft nodes before linearized reading' (duration: 1.437323361s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.886164Z","time spent":"1.437525546s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-25T12:47:04.32373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533508385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3842"} {"level":"warn","ts":"2026-02-25T12:47:04.32361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.936802Z","time spent":"1.386799518s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-25T12:47:04.32375Z","caller":"traceutil/trace.go:171","msg":"trace[1853030301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:302; }","duration":"1.533548917s","start":"2026-02-25T12:47:02.790195Z","end":"2026-02-25T12:47:04.323744Z","steps":["trace[1853030301] 'agreement among raft nodes before linearized reading' (duration: 1.533501196s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.546271Z","time spent":"1.777041493s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T12:47:04.323769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.790185Z","time spent":"1.533579007s","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3865,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T12:47:04.323931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637514423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T12:47:04.323956Z","caller":"traceutil/trace.go:171","msg":"trace[1484931226] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.637589655s","start":"2026-02-25T12:47:02.686358Z","end":"2026-02-25T12:47:04.323948Z","steps":["trace[1484931226] 'agreement among raft nodes before linearized reading' (duration: 1.637464832s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.323975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.686327Z","time spent":"1.637643316s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.323991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.996322ms","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-02-25T12:47:04.324028Z","caller":"traceutil/trace.go:171","msg":"trace[1817825112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:302; }","duration":"633.067654ms","start":"2026-02-25T12:47:03.690949Z","end":"2026-02-25T12:47:04.324017Z","steps":["trace[1817825112] 'agreement among raft nodes before linearized reading' (duration: 632.999503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.324038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534263284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-25T12:47:04.324043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338211539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T12:47:04.324054Z","caller":"traceutil/trace.go:171","msg":"trace[1572465007] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:302; }","duration":"1.534304304s","start":"2026-02-25T12:47:02.789745Z","end":"2026-02-25T12:47:04.324049Z","steps":["trace[1572465007] 'agreement among raft nodes before linearized reading' (duration: 1.534271003s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.324058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:03.690922Z","time spent":"633.127416ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T12:47:04.324065Z","caller":"traceutil/trace.go:171","msg":"trace[1176188709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.33825327s","start":"2026-02-25T12:47:02.985806Z","end":"2026-02-25T12:47:04.324059Z","steps":["trace[1176188709] 'agreement among raft nodes before linearized reading' (duration: 1.338217339s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.32407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.789732Z","time spent":"1.534335185s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-25T12:47:04.324084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.985794Z","time spent":"1.338286021s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.324128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537370567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T12:47:04.324143Z","caller":"traceutil/trace.go:171","msg":"trace[1575022959] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.537431518s","start":"2026-02-25T12:47:02.786706Z","end":"2026-02-25T12:47:04.324137Z","steps":["trace[1575022959] 'agreement among raft nodes before linearized reading' (duration: 1.537403367s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.32416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.786681Z","time spent":"1.537473418s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.32416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58790641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T12:47:04.324185Z","caller":"traceutil/trace.go:171","msg":"trace[762375848] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:302; }","duration":"1.587967112s","start":"2026-02-25T12:47:02.73621Z","end":"2026-02-25T12:47:04.324177Z","steps":["trace[762375848] 'agreement among raft nodes before linearized reading' (duration: 1.587922201s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.324193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.395325ms","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-02-25T12:47:04.324214Z","caller":"traceutil/trace.go:171","msg":"trace[635593211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:302; }","duration":"954.414616ms","start":"2026-02-25T12:47:03.369792Z","end":"2026-02-25T12:47:04.324207Z","steps":["trace[635593211] 'agreement among raft nodes before linearized reading' (duration: 954.378355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:04.324209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:02.736191Z","time spent":"1.588012963s","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:04.324231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:03.369734Z","time spent":"954.492727ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T12:47:05.107242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.656599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T12:47:05.107345Z","caller":"traceutil/trace.go:171","msg":"trace[1290727377] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"777.484858ms","start":"2026-02-25T12:47:04.329842Z","end":"2026-02-25T12:47:05.107327Z","steps":["trace[1290727377] 'process raft request' (duration: 175.43426ms)","trace[1290727377] 'compare' (duration: 601.224359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T12:47:05.107404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.329827Z","time spent":"777.54925ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:47:05.135488Z","caller":"traceutil/trace.go:171","msg":"trace[1141568218] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"804.490421ms","start":"2026-02-25T12:47:04.330971Z","end":"2026-02-25T12:47:05.135461Z","steps":["trace[1141568218] 'process raft request' (duration: 804.310637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:05.13574Z","caller":"traceutil/trace.go:171","msg":"trace[1232571054] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"804.708677ms","start":"2026-02-25T12:47:04.331013Z","end":"2026-02-25T12:47:05.135721Z","steps":["trace[1232571054] 'process raft request' (duration: 804.401579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.135884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331008Z","time spent":"804.78454ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:05.135776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.330963Z","time spent":"804.623285ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:47:05.136025Z","caller":"traceutil/trace.go:171","msg":"trace[1264378494] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"804.358719ms","start":"2026-02-25T12:47:04.331641Z","end":"2026-02-25T12:47:05.136Z","steps":["trace[1264378494] 'process raft request' (duration: 804.004351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:05.136091Z","caller":"traceutil/trace.go:171","msg":"trace[599863079] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"803.502799ms","start":"2026-02-25T12:47:04.332577Z","end":"2026-02-25T12:47:05.13608Z","steps":["trace[599863079] 'process raft request' (duration: 803.391316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.136151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.332569Z","time spent":"803.5532ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:47:05.136204Z","caller":"traceutil/trace.go:171","msg":"trace[1779242653] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"803.404436ms","start":"2026-02-25T12:47:04.332787Z","end":"2026-02-25T12:47:05.136192Z","steps":["trace[1779242653] 'process raft request' (duration: 803.237242ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:05.136242Z","caller":"traceutil/trace.go:171","msg":"trace[536558955] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"804.37709ms","start":"2026-02-25T12:47:04.331829Z","end":"2026-02-25T12:47:05.136206Z","steps":["trace[536558955] 'process raft request' (duration: 804.044902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.136358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331822Z","time spent":"804.485502ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T12:47:05.136144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331636Z","time spent":"804.451151ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:05.136276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.332781Z","time spent":"803.464069ms","remote":"127.0.0.1:60516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T12:47:05.136574Z","caller":"traceutil/trace.go:171","msg":"trace[878628981] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"802.567737ms","start":"2026-02-25T12:47:04.333972Z","end":"2026-02-25T12:47:05.13654Z","steps":["trace[878628981] 'process raft request' (duration: 802.087166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.136716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.333965Z","time spent":"802.662269ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T12:47:05.137073Z","caller":"traceutil/trace.go:171","msg":"trace[1598979730] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:315; }","duration":"805.840314ms","start":"2026-02-25T12:47:04.331105Z","end":"2026-02-25T12:47:05.136945Z","steps":["trace[1598979730] 'read index received' (duration: 174.11351ms)","trace[1598979730] 'applied index is now lower than readState.Index' (duration: 631.725714ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:05.137182Z","caller":"traceutil/trace.go:171","msg":"trace[1059892787] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"802.783203ms","start":"2026-02-25T12:47:04.334383Z","end":"2026-02-25T12:47:05.137166Z","steps":["trace[1059892787] 'process raft request' (duration: 802.171269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.138544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.334377Z","time spent":"804.071263ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:05.137377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.263053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T12:47:05.138918Z","caller":"traceutil/trace.go:171","msg":"trace[1969879436] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"807.653117ms","start":"2026-02-25T12:47:04.331086Z","end":"2026-02-25T12:47:05.138739Z","steps":["trace[1969879436] 'agreement among raft nodes before linearized reading' (duration: 806.147881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.139014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.33108Z","time spent":"807.887512ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:05.137635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.174081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-02-25T12:47:05.137682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.358372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T12:47:05.139454Z","caller":"traceutil/trace.go:171","msg":"trace[2063185145] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:311; }","duration":"807.129893ms","start":"2026-02-25T12:47:04.33231Z","end":"2026-02-25T12:47:05.13944Z","steps":["trace[2063185145] 'agreement among raft nodes before linearized reading' (duration: 805.347221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.139532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.332306Z","time spent":"807.211966ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-02-25T12:47:05.139418Z","caller":"traceutil/trace.go:171","msg":"trace[1663075451] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"807.734229ms","start":"2026-02-25T12:47:04.331428Z","end":"2026-02-25T12:47:05.139162Z","steps":["trace[1663075451] 'agreement among raft nodes before linearized reading' (duration: 806.169412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.139606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331424Z","time spent":"808.169859ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:05.137714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.641138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T12:47:05.139727Z","caller":"traceutil/trace.go:171","msg":"trace[1069998954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:311; }","duration":"807.644556ms","start":"2026-02-25T12:47:04.332069Z","end":"2026-02-25T12:47:05.139713Z","steps":["trace[1069998954] 'agreement among raft nodes before linearized reading' (duration: 805.628418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.139764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.332055Z","time spent":"807.699927ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:05.137748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.752251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T12:47:05.139903Z","caller":"traceutil/trace.go:171","msg":"trace[811886865] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:311; }","duration":"807.83632ms","start":"2026-02-25T12:47:04.331982Z","end":"2026-02-25T12:47:05.139818Z","steps":["trace[811886865] 'agreement among raft nodes before linearized reading' (duration: 805.748101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.139979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331977Z","time spent":"807.964173ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-25T12:47:05.137892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.348946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3842"} {"level":"info","ts":"2026-02-25T12:47:05.140071Z","caller":"traceutil/trace.go:171","msg":"trace[942199103] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"808.535957ms","start":"2026-02-25T12:47:04.33152Z","end":"2026-02-25T12:47:05.140056Z","steps":["trace[942199103] 'agreement among raft nodes before linearized reading' (duration: 806.301135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:05.140112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:04.331506Z","time spent":"808.595369ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3865,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T12:47:26.134877Z","caller":"traceutil/trace.go:171","msg":"trace[763906901] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"522.156777ms","start":"2026-02-25T12:47:25.612693Z","end":"2026-02-25T12:47:26.13485Z","steps":["trace[763906901] 'process raft request' (duration: 521.90419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:26.135148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:25.61268Z","time spent":"522.284069ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:47:26.135621Z","caller":"traceutil/trace.go:171","msg":"trace[524025096] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"167.747181ms","start":"2026-02-25T12:47:25.967839Z","end":"2026-02-25T12:47:26.135586Z","steps":["trace[524025096] 'process raft request' (duration: 167.626458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:33.043749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.407529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T12:47:33.043866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.681278ms","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-02-25T12:47:33.043996Z","caller":"traceutil/trace.go:171","msg":"trace[1026928746] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"374.602888ms","start":"2026-02-25T12:47:32.669368Z","end":"2026-02-25T12:47:33.043971Z","steps":["trace[1026928746] 'read index received' (duration: 47.841µs)","trace[1026928746] 'applied index is now lower than readState.Index' (duration: 374.553077ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:33.044041Z","caller":"traceutil/trace.go:171","msg":"trace[1488393319] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:489; }","duration":"114.880962ms","start":"2026-02-25T12:47:32.929131Z","end":"2026-02-25T12:47:33.044012Z","steps":["trace[1488393319] 'range keys from in-memory index tree' (duration: 114.649287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:33.044242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.850635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d295c\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T12:47:33.044311Z","caller":"traceutil/trace.go:171","msg":"trace[597618343] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d295c; range_end:; response_count:1; response_revision:489; }","duration":"374.966047ms","start":"2026-02-25T12:47:32.669318Z","end":"2026-02-25T12:47:33.044284Z","steps":["trace[597618343] 'agreement among raft nodes before linearized reading' (duration: 374.719751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:33.044359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:32.669298Z","time spent":"375.050009ms","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-d295c\" "} {"level":"info","ts":"2026-02-25T12:47:33.283186Z","caller":"traceutil/trace.go:171","msg":"trace[904132390] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"135.189918ms","start":"2026-02-25T12:47:33.147975Z","end":"2026-02-25T12:47:33.283165Z","steps":["trace[904132390] 'read index received' (duration: 134.961543ms)","trace[904132390] 'applied index is now lower than readState.Index' (duration: 227.465µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:33.283276Z","caller":"traceutil/trace.go:171","msg":"trace[628516812] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"226.786994ms","start":"2026-02-25T12:47:33.056471Z","end":"2026-02-25T12:47:33.283258Z","steps":["trace[628516812] 'process raft request' (duration: 226.456576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:33.28396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.414383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T12:47:33.284078Z","caller":"traceutil/trace.go:171","msg":"trace[393303758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"136.10094ms","start":"2026-02-25T12:47:33.147919Z","end":"2026-02-25T12:47:33.28402Z","steps":["trace[393303758] 'agreement among raft nodes before linearized reading' (duration: 135.435023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:34.394908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:33.712545Z","time spent":"682.355409ms","remote":"127.0.0.1:50364","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T12:47:34.395005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.799659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T12:47:34.395245Z","caller":"traceutil/trace.go:171","msg":"trace[1444275629] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:511; }","duration":"246.890376ms","start":"2026-02-25T12:47:34.148338Z","end":"2026-02-25T12:47:34.395229Z","steps":["trace[1444275629] 'read index received' (duration: 117.77921ms)","trace[1444275629] 'applied index is now lower than readState.Index' (duration: 129.110286ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:34.395413Z","caller":"traceutil/trace.go:171","msg":"trace[423966261] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.106343116s","start":"2026-02-25T12:47:33.289045Z","end":"2026-02-25T12:47:34.395388Z","steps":["trace[423966261] 'process raft request' (duration: 1.106040059s)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:34.39543Z","caller":"traceutil/trace.go:171","msg":"trace[1008663571] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.106682484s","start":"2026-02-25T12:47:33.288718Z","end":"2026-02-25T12:47:34.395401Z","steps":["trace[1008663571] 'process raft request' (duration: 977.400444ms)","trace[1008663571] 'compare' (duration: 128.680726ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:47:34.395458Z","caller":"traceutil/trace.go:171","msg":"trace[636432405] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.105661239s","start":"2026-02-25T12:47:33.289779Z","end":"2026-02-25T12:47:34.395441Z","steps":["trace[636432405] 'process raft request' (duration: 1.105379872s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:34.39552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:33.289024Z","time spent":"1.106425678s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:34.395538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:33.28975Z","time spent":"1.105747541s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:34.395564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:33.288699Z","time spent":"1.106795616s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:47:34.395743Z","caller":"traceutil/trace.go:171","msg":"trace[845787820] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.104579894s","start":"2026-02-25T12:47:33.291154Z","end":"2026-02-25T12:47:34.395734Z","steps":["trace[845787820] 'process raft request' (duration: 1.104041701s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:34.395796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:47:33.291144Z","time spent":"1.104621535s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:47:34.395916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.584962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T12:47:34.395988Z","caller":"traceutil/trace.go:171","msg":"trace[355432213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"247.689605ms","start":"2026-02-25T12:47:34.148288Z","end":"2026-02-25T12:47:34.395977Z","steps":["trace[355432213] 'agreement among raft nodes before linearized reading' (duration: 247.411568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:37.118725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.174932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T12:47:37.118864Z","caller":"traceutil/trace.go:171","msg":"trace[1872749732] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:510; }","duration":"212.371136ms","start":"2026-02-25T12:47:36.906459Z","end":"2026-02-25T12:47:37.11883Z","steps":["trace[1872749732] 'range keys from in-memory index tree' (duration: 211.903185ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:53.366372Z","caller":"traceutil/trace.go:171","msg":"trace[439811349] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"129.280659ms","start":"2026-02-25T12:47:53.237072Z","end":"2026-02-25T12:47:53.366352Z","steps":["trace[439811349] 'process raft request' (duration: 129.162346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:47:55.443342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.38601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T12:47:55.443359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.784657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T12:47:55.443437Z","caller":"traceutil/trace.go:171","msg":"trace[1821845311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"294.532764ms","start":"2026-02-25T12:47:55.148881Z","end":"2026-02-25T12:47:55.443414Z","steps":["trace[1821845311] 'range keys from in-memory index tree' (duration: 294.284638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:55.44345Z","caller":"traceutil/trace.go:171","msg":"trace[1838554335] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:685; }","duration":"178.941051ms","start":"2026-02-25T12:47:55.264486Z","end":"2026-02-25T12:47:55.443427Z","steps":["trace[1838554335] 'count revisions from in-memory index tree' (duration: 178.616964ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:47:55.561248Z","caller":"traceutil/trace.go:171","msg":"trace[959264918] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"113.885412ms","start":"2026-02-25T12:47:55.447339Z","end":"2026-02-25T12:47:55.561225Z","steps":["trace[959264918] 'process raft request' (duration: 113.690768ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:16.818108Z","caller":"traceutil/trace.go:171","msg":"trace[1715280213] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"101.256911ms","start":"2026-02-25T12:48:16.716826Z","end":"2026-02-25T12:48:16.818083Z","steps":["trace[1715280213] 'process raft request' (duration: 100.861372ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:17.581948Z","caller":"traceutil/trace.go:171","msg":"trace[1834707074] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"132.606157ms","start":"2026-02-25T12:48:17.449319Z","end":"2026-02-25T12:48:17.581925Z","steps":["trace[1834707074] 'process raft request' (duration: 132.467554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:41.025119Z","caller":"traceutil/trace.go:171","msg":"trace[1431709572] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"225.278714ms","start":"2026-02-25T12:48:40.799815Z","end":"2026-02-25T12:48:41.025094Z","steps":["trace[1431709572] 'process raft request' (duration: 225.149301ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:41.3486Z","caller":"traceutil/trace.go:171","msg":"trace[667828941] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"546.360951ms","start":"2026-02-25T12:48:40.802179Z","end":"2026-02-25T12:48:41.34854Z","steps":["trace[667828941] 'process raft request' (duration: 497.967029ms)","trace[667828941] 'compare' (duration: 48.072674ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:48:41.348587Z","caller":"traceutil/trace.go:171","msg":"trace[676262231] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:905; }","duration":"505.160369ms","start":"2026-02-25T12:48:40.843381Z","end":"2026-02-25T12:48:41.348541Z","steps":["trace[676262231] 'read index received' (duration: 182.003154ms)","trace[676262231] 'applied index is now lower than readState.Index' (duration: 323.155455ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:48:41.348633Z","caller":"traceutil/trace.go:171","msg":"trace[1298021205] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"512.218547ms","start":"2026-02-25T12:48:40.836374Z","end":"2026-02-25T12:48:41.348593Z","steps":["trace[1298021205] 'process raft request' (duration: 512.052893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:41.34881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:40.802165Z","time spent":"546.534565ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:48:41.348937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.541489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T12:48:41.348986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.268824ms","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-02-25T12:48:41.349051Z","caller":"traceutil/trace.go:171","msg":"trace[204628358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"398.45158ms","start":"2026-02-25T12:48:40.950581Z","end":"2026-02-25T12:48:41.349033Z","steps":["trace[204628358] 'agreement among raft nodes before linearized reading' (duration: 398.211503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:41.349105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:40.950556Z","time spent":"398.539241ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T12:48:41.348982Z","caller":"traceutil/trace.go:171","msg":"trace[898220100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"505.625931ms","start":"2026-02-25T12:48:40.843346Z","end":"2026-02-25T12:48:41.348972Z","steps":["trace[898220100] 'agreement among raft nodes before linearized reading' (duration: 505.308263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:41.349184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:40.843333Z","time spent":"505.830766ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T12:48:41.34941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:40.836343Z","time spent":"512.617827ms","remote":"127.0.0.1:60502","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-02-25T12:48:51.603943Z","caller":"traceutil/trace.go:171","msg":"trace[1433917620] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"202.906737ms","start":"2026-02-25T12:48:51.401005Z","end":"2026-02-25T12:48:51.603912Z","steps":["trace[1433917620] 'process raft request' (duration: 202.706533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:51.630173Z","caller":"traceutil/trace.go:171","msg":"trace[1902197733] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"229.08855ms","start":"2026-02-25T12:48:51.401066Z","end":"2026-02-25T12:48:51.630154Z","steps":["trace[1902197733] 'process raft request' (duration: 228.68047ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:51.630343Z","caller":"traceutil/trace.go:171","msg":"trace[364522518] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"228.701042ms","start":"2026-02-25T12:48:51.401629Z","end":"2026-02-25T12:48:51.63033Z","steps":["trace[364522518] 'process raft request' (duration: 228.185109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:51.630479Z","caller":"traceutil/trace.go:171","msg":"trace[1581314662] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"209.578181ms","start":"2026-02-25T12:48:51.420892Z","end":"2026-02-25T12:48:51.63047Z","steps":["trace[1581314662] 'process raft request' (duration: 208.957346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:57.398798Z","caller":"traceutil/trace.go:171","msg":"trace[1122836440] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"298.867455ms","start":"2026-02-25T12:48:57.099908Z","end":"2026-02-25T12:48:57.398775Z","steps":["trace[1122836440] 'process raft request' (duration: 298.764543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:57.981817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.23039ms","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-02-25T12:48:57.981899Z","caller":"traceutil/trace.go:171","msg":"trace[932635610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"118.374643ms","start":"2026-02-25T12:48:57.863508Z","end":"2026-02-25T12:48:57.981883Z","steps":["trace[932635610] 'range keys from in-memory index tree' (duration: 118.033865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:57.982281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.149766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T12:48:57.982361Z","caller":"traceutil/trace.go:171","msg":"trace[61745619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"314.269899ms","start":"2026-02-25T12:48:57.66807Z","end":"2026-02-25T12:48:57.98234Z","steps":["trace[61745619] 'range keys from in-memory index tree' (duration: 313.928762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:57.982406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:57.668055Z","time spent":"314.34195ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T12:48:57.982501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.437ms","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-02-25T12:48:57.982541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.630932ms","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-02-25T12:48:57.982579Z","caller":"traceutil/trace.go:171","msg":"trace[1831425028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"283.562883ms","start":"2026-02-25T12:48:57.698992Z","end":"2026-02-25T12:48:57.982555Z","steps":["trace[1831425028] 'range keys from in-memory index tree' (duration: 282.97198ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:57.982619Z","caller":"traceutil/trace.go:171","msg":"trace[1683989310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"332.750404ms","start":"2026-02-25T12:48:57.64985Z","end":"2026-02-25T12:48:57.9826Z","steps":["trace[1683989310] 'range keys from in-memory index tree' (duration: 332.11579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:57.982692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:57.649832Z","time spent":"332.850277ms","remote":"127.0.0.1:60502","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-02-25T12:48:58.170019Z","caller":"traceutil/trace.go:171","msg":"trace[432927621] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"182.505978ms","start":"2026-02-25T12:48:57.987466Z","end":"2026-02-25T12:48:58.169972Z","steps":["trace[432927621] 'process raft request' (duration: 182.281823ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:58.170238Z","caller":"traceutil/trace.go:171","msg":"trace[1388692526] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"183.46941ms","start":"2026-02-25T12:48:57.986711Z","end":"2026-02-25T12:48:58.17018Z","steps":["trace[1388692526] 'process raft request' (duration: 109.065449ms)","trace[1388692526] 'compare' (duration: 73.803418ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:48:58.170419Z","caller":"traceutil/trace.go:171","msg":"trace[580955782] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"183.202083ms","start":"2026-02-25T12:48:57.987166Z","end":"2026-02-25T12:48:58.170368Z","steps":["trace[580955782] 'process raft request' (duration: 182.542458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:58.963543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.95138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T12:48:58.963615Z","caller":"traceutil/trace.go:171","msg":"trace[629715674] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:940; }","duration":"423.063382ms","start":"2026-02-25T12:48:58.540537Z","end":"2026-02-25T12:48:58.963601Z","steps":["trace[629715674] 'range keys from in-memory index tree' (duration: 422.898988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:58.963641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:58.540521Z","time spent":"423.114873ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T12:48:59.097466Z","caller":"traceutil/trace.go:171","msg":"trace[1743736010] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"128.724971ms","start":"2026-02-25T12:48:58.968718Z","end":"2026-02-25T12:48:59.097443Z","steps":["trace[1743736010] 'read index received' (duration: 128.534617ms)","trace[1743736010] 'applied index is now lower than readState.Index' (duration: 189.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:48:59.097509Z","caller":"traceutil/trace.go:171","msg":"trace[1371233046] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"225.684271ms","start":"2026-02-25T12:48:58.871796Z","end":"2026-02-25T12:48:59.09748Z","steps":["trace[1371233046] 'process raft request' (duration: 225.504737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:59.09758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.852055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T12:48:59.097614Z","caller":"traceutil/trace.go:171","msg":"trace[1456926388] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:941; }","duration":"128.934497ms","start":"2026-02-25T12:48:58.968668Z","end":"2026-02-25T12:48:59.097603Z","steps":["trace[1456926388] 'agreement among raft nodes before linearized reading' (duration: 128.847545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:48:59.225454Z","caller":"traceutil/trace.go:171","msg":"trace[1270957641] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"124.135055ms","start":"2026-02-25T12:48:59.101288Z","end":"2026-02-25T12:48:59.225423Z","steps":["trace[1270957641] 'process raft request' (duration: 74.681257ms)","trace[1270957641] 'compare' (duration: 49.316725ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:48:59.969818Z","caller":"traceutil/trace.go:171","msg":"trace[182686392] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"522.556591ms","start":"2026-02-25T12:48:59.44723Z","end":"2026-02-25T12:48:59.969787Z","steps":["trace[182686392] 'process raft request' (duration: 522.387827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:48:59.970113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:48:59.447211Z","time spent":"522.770096ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:49:00.236784Z","caller":"traceutil/trace.go:171","msg":"trace[615904177] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"208.771823ms","start":"2026-02-25T12:49:00.027988Z","end":"2026-02-25T12:49:00.23676Z","steps":["trace[615904177] 'process raft request' (duration: 162.362125ms)","trace[615904177] 'compare' (duration: 46.003649ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:52:15.447383Z","caller":"traceutil/trace.go:171","msg":"trace[1774167397] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"160.866156ms","start":"2026-02-25T12:52:15.286489Z","end":"2026-02-25T12:52:15.447355Z","steps":["trace[1774167397] 'read index received' (duration: 160.668542ms)","trace[1774167397] 'applied index is now lower than readState.Index' (duration: 196.664µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:52:15.447514Z","caller":"traceutil/trace.go:171","msg":"trace[652156477] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"166.41275ms","start":"2026-02-25T12:52:15.281089Z","end":"2026-02-25T12:52:15.447502Z","steps":["trace[652156477] 'process raft request' (duration: 166.121554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:15.448056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.540511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T12:52:15.448167Z","caller":"traceutil/trace.go:171","msg":"trace[1197358895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1800; }","duration":"161.698925ms","start":"2026-02-25T12:52:15.286439Z","end":"2026-02-25T12:52:15.448138Z","steps":["trace[1197358895] 'agreement among raft nodes before linearized reading' (duration: 161.393258ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:52:15.755102Z","caller":"traceutil/trace.go:171","msg":"trace[718675171] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"303.814546ms","start":"2026-02-25T12:52:15.451254Z","end":"2026-02-25T12:52:15.755069Z","steps":["trace[718675171] 'process raft request' (duration: 301.342031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:15.755277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:52:15.451235Z","time spent":"303.9556ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T12:52:15.76481Z","caller":"traceutil/trace.go:171","msg":"trace[174195407] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"312.789699ms","start":"2026-02-25T12:52:15.451998Z","end":"2026-02-25T12:52:15.764787Z","steps":["trace[174195407] 'process raft request' (duration: 312.654106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:15.764934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:52:15.451986Z","time spent":"312.888381ms","remote":"127.0.0.1:60502","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-02-25T12:52:15.764973Z","caller":"traceutil/trace.go:171","msg":"trace[2061940028] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"312.255596ms","start":"2026-02-25T12:52:15.452704Z","end":"2026-02-25T12:52:15.76496Z","steps":["trace[2061940028] 'process raft request' (duration: 312.055092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:15.765053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T12:52:15.452695Z","time spent":"312.315267ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T12:52:23.62039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.752598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-02-25T12:52:23.620482Z","caller":"traceutil/trace.go:171","msg":"trace[1832906176] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1837; }","duration":"174.8814ms","start":"2026-02-25T12:52:23.445581Z","end":"2026-02-25T12:52:23.620463Z","steps":["trace[1832906176] 'range keys from in-memory index tree' (duration: 174.609604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:23.620561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.116506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:944"} {"level":"info","ts":"2026-02-25T12:52:23.620652Z","caller":"traceutil/trace.go:171","msg":"trace[1612539873] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1837; }","duration":"187.246219ms","start":"2026-02-25T12:52:23.433384Z","end":"2026-02-25T12:52:23.620631Z","steps":["trace[1612539873] 'range keys from in-memory index tree' (duration: 186.962202ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T12:52:38.54933Z","caller":"traceutil/trace.go:171","msg":"trace[1574191991] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"167.526864ms","start":"2026-02-25T12:52:38.381781Z","end":"2026-02-25T12:52:38.549308Z","steps":["trace[1574191991] 'read index received' (duration: 167.32639ms)","trace[1574191991] 'applied index is now lower than readState.Index' (duration: 199.444µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T12:52:38.549441Z","caller":"traceutil/trace.go:171","msg":"trace[1991559862] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"178.383329ms","start":"2026-02-25T12:52:38.371018Z","end":"2026-02-25T12:52:38.549401Z","steps":["trace[1991559862] 'process raft request' (duration: 178.156494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:38.549561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.75242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T12:52:38.549627Z","caller":"traceutil/trace.go:171","msg":"trace[1775101223] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1904; }","duration":"167.859343ms","start":"2026-02-25T12:52:38.381752Z","end":"2026-02-25T12:52:38.549612Z","steps":["trace[1775101223] 'agreement among raft nodes before linearized reading' (duration: 167.672358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T12:52:38.549636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.350386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kube-proxy\" ","response":"range_response_count:1 size:317"} {"level":"info","ts":"2026-02-25T12:52:38.549768Z","caller":"traceutil/trace.go:171","msg":"trace[33132616] range","detail":"{range_begin:/registry/roles/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1904; }","duration":"153.52494ms","start":"2026-02-25T12:52:38.39623Z","end":"2026-02-25T12:52:38.549755Z","steps":["trace[33132616] 'agreement among raft nodes before linearized reading' (duration: 153.197362ms)"],"step_count":1}