{"level":"warn","ts":"2026-02-26T20:56:19.648088Z","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-26T20:56:19.648162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-26T20:56:19.648229Z","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-26T20:56:19.648243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-26T20:56:19.64826Z","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-26T20:56:19.64895Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-02-26T20:56:19.649046Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-26T20:56:19.773612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"101.318087ms"} {"level":"info","ts":"2026-02-26T20:56:20.101694Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-02-26T20:56:20.101837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T20:56:20.101869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-02-26T20:56:20.101886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T20:56:20.101904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-02-26T20:56:20.101958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-02-26T20:56:20.163185Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T20:56:20.203399Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T20:56:20.233016Z","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-26T20:56:20.242392Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T20:56:20.242656Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T20:56:20.242963Z","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-26T20:56:20.243055Z","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-26T20:56:20.243077Z","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-26T20:56:20.248076Z","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-26T20:56:20.248158Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-26T20:56:20.248188Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-26T20:56:20.248445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T20:56:20.248492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T20:56:20.265249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-02-26T20:56:20.265418Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-26T20:56:20.603053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T20:56:20.603209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T20:56:20.603254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-02-26T20:56:20.603274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-02-26T20:56:20.60329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-26T20:56:20.603301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-02-26T20:56:20.603318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-26T20:56:20.613886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:56:20.6152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:56:20.615196Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T20:56:20.615315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:56:20.615624Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:56:20.616161Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T20:56:20.616229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T20:56:20.616243Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:56:20.616285Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:56:20.619417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-02-26T20:56:20.619708Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-26T20:56:20.774567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.20228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:20.774667Z","caller":"traceutil/trace.go:171","msg":"trace[1466648740] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"109.315641ms","start":"2026-02-26T20:56:20.665334Z","end":"2026-02-26T20:56:20.77465Z","steps":["trace[1466648740] 'range keys from in-memory index tree' (duration: 109.130739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:20.776457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.867755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:20.776582Z","caller":"traceutil/trace.go:171","msg":"trace[64517128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"111.073776ms","start":"2026-02-26T20:56:20.665477Z","end":"2026-02-26T20:56:20.77655Z","steps":["trace[64517128] 'count revisions from in-memory index tree' (duration: 110.738554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:22.306396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.584679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:56:22.306531Z","caller":"traceutil/trace.go:171","msg":"trace[1378771998] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"218.667435ms","start":"2026-02-26T20:56:22.087844Z","end":"2026-02-26T20:56:22.306512Z","steps":["trace[1378771998] 'process raft request' (duration: 23.42452ms)","trace[1378771998] 'compare' (duration: 194.422498ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:56:22.332136Z","caller":"traceutil/trace.go:171","msg":"trace[433990481] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"242.659141ms","start":"2026-02-26T20:56:22.089441Z","end":"2026-02-26T20:56:22.3321Z","steps":["trace[433990481] 'process raft request' (duration: 242.53991ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.332211Z","caller":"traceutil/trace.go:171","msg":"trace[1116843678] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"242.717452ms","start":"2026-02-26T20:56:22.089488Z","end":"2026-02-26T20:56:22.332205Z","steps":["trace[1116843678] 'process raft request' (duration: 242.52308ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.33214Z","caller":"traceutil/trace.go:171","msg":"trace[2006702153] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"242.738322ms","start":"2026-02-26T20:56:22.08938Z","end":"2026-02-26T20:56:22.332119Z","steps":["trace[2006702153] 'process raft request' (duration: 242.52459ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.332423Z","caller":"traceutil/trace.go:171","msg":"trace[1118816999] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"242.938233ms","start":"2026-02-26T20:56:22.089476Z","end":"2026-02-26T20:56:22.332414Z","steps":["trace[1118816999] 'process raft request' (duration: 242.55277ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.332707Z","caller":"traceutil/trace.go:171","msg":"trace[351761428] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"243.121725ms","start":"2026-02-26T20:56:22.089575Z","end":"2026-02-26T20:56:22.332697Z","steps":["trace[351761428] 'process raft request' (duration: 242.780632ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.333021Z","caller":"traceutil/trace.go:171","msg":"trace[545216128] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:14; }","duration":"201.551022ms","start":"2026-02-26T20:56:22.131457Z","end":"2026-02-26T20:56:22.333008Z","steps":["trace[545216128] 'read index received' (duration: 200.504373ms)","trace[545216128] 'applied index is now lower than readState.Index' (duration: 1.045869ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:56:22.333097Z","caller":"traceutil/trace.go:171","msg":"trace[1747968070] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"243.423507ms","start":"2026-02-26T20:56:22.089643Z","end":"2026-02-26T20:56:22.333066Z","steps":["trace[1747968070] 'process raft request' (duration: 242.749891ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.333161Z","caller":"traceutil/trace.go:171","msg":"trace[173508467] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"243.457757ms","start":"2026-02-26T20:56:22.089648Z","end":"2026-02-26T20:56:22.333106Z","steps":["trace[173508467] 'process raft request' (duration: 243.025184ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:22.333245Z","caller":"traceutil/trace.go:171","msg":"trace[2044243087] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"243.566419ms","start":"2026-02-26T20:56:22.08967Z","end":"2026-02-26T20:56:22.333237Z","steps":["trace[2044243087] 'process raft request' (duration: 243.300556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:22.333439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.974305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-26T20:56:22.333472Z","caller":"traceutil/trace.go:171","msg":"trace[1500795126] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"202.052535ms","start":"2026-02-26T20:56:22.131411Z","end":"2026-02-26T20:56:22.333463Z","steps":["trace[1500795126] 'agreement among raft nodes before linearized reading' (duration: 201.953185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:22.834142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190919826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:56:23.336596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190919826,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:56:23.823793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491891382s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T20:56:25.088845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000262351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-26T20:56:25.08911Z","caller":"traceutil/trace.go:171","msg":"trace[103413064] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000562103s","start":"2026-02-26T20:56:23.08853Z","end":"2026-02-26T20:56:25.089092Z","steps":["trace[103413064] 'agreement among raft nodes before linearized reading' (duration: 2.00025959s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:25.089228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:23.088449Z","time spent":"2.000764635s","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 20:56:25 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-26T20:56:25.31167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487433119s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:56:25.312051Z","caller":"traceutil/trace.go:171","msg":"trace[1220827311] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"2.983236267s","start":"2026-02-26T20:56:22.32876Z","end":"2026-02-26T20:56:25.311997Z","steps":["trace[1220827311] 'process raft request' (duration: 1.495266213s)","trace[1220827311] 'compare' (duration: 1.487291467s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:56:25.312289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.328733Z","time spent":"2.983510439s","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:56:25.848484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.838095753s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T20:56:26.228682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999947159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-26T20:56:26.228771Z","caller":"traceutil/trace.go:171","msg":"trace[20528544] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00009316s","start":"2026-02-26T20:56:24.22866Z","end":"2026-02-26T20:56:26.228753Z","steps":["trace[20528544] 'agreement among raft nodes before linearized reading' (duration: 1.999980789s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.228835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:24.228613Z","time spent":"2.000204122s","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:56:26.490019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.307333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:56:26.490853Z","caller":"traceutil/trace.go:171","msg":"trace[1945793544] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"4.15439403s","start":"2026-02-26T20:56:22.336421Z","end":"2026-02-26T20:56:26.490815Z","steps":["trace[1945793544] 'process raft request' (duration: 3.512198368s)","trace[1945793544] 'compare' (duration: 641.057981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:56:26.491835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.336406Z","time spent":"4.154524531s","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:56:26.493458Z","caller":"traceutil/trace.go:171","msg":"trace[1947189808] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:22; }","duration":"4.160331042s","start":"2026-02-26T20:56:22.33305Z","end":"2026-02-26T20:56:26.493381Z","steps":["trace[1947189808] 'read index received' (duration: 1.490989015s)","trace[1947189808] 'applied index is now lower than readState.Index' (duration: 2.669256056s)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:56:26.49418Z","caller":"traceutil/trace.go:171","msg":"trace[7506876] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"4.157382905s","start":"2026-02-26T20:56:22.336586Z","end":"2026-02-26T20:56:26.493969Z","steps":["trace[7506876] 'process raft request' (duration: 4.156270125s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.494314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.336575Z","time spent":"4.157660877s","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:56:26.494863Z","caller":"traceutil/trace.go:171","msg":"trace[213840776] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"4.158061611s","start":"2026-02-26T20:56:22.336656Z","end":"2026-02-26T20:56:26.494718Z","steps":["trace[213840776] 'process raft request' (duration: 4.156388346s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.49514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.336648Z","time spent":"4.158441094s","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:56:26.495508Z","caller":"traceutil/trace.go:171","msg":"trace[1163322157] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"4.158679276s","start":"2026-02-26T20:56:22.336499Z","end":"2026-02-26T20:56:26.495178Z","steps":["trace[1163322157] 'process raft request' (duration: 4.154155096s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:26.495548Z","caller":"traceutil/trace.go:171","msg":"trace[719829932] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"4.158462284s","start":"2026-02-26T20:56:22.337059Z","end":"2026-02-26T20:56:26.495522Z","steps":["trace[719829932] 'process raft request' (duration: 4.156039253s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.495655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.336476Z","time spent":"4.159092679s","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:56:26.495666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.337051Z","time spent":"4.158583445s","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:56:26.495761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.161208279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-26T20:56:26.495815Z","caller":"traceutil/trace.go:171","msg":"trace[188310443] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"4.161286139s","start":"2026-02-26T20:56:22.334507Z","end":"2026-02-26T20:56:26.495794Z","steps":["trace[188310443] 'agreement among raft nodes before linearized reading' (duration: 4.161133688s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.495846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.334497Z","time spent":"4.161340801s","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-26T20:56:26.495868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.322317934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:26.495946Z","caller":"traceutil/trace.go:171","msg":"trace[1977068601] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:26; }","duration":"3.322417874s","start":"2026-02-26T20:56:23.173501Z","end":"2026-02-26T20:56:26.495919Z","steps":["trace[1977068601] 'agreement among raft nodes before linearized reading' (duration: 3.322142802s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.495987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:23.173486Z","time spent":"3.322491645s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-02-26T20:56:26.49604Z","caller":"traceutil/trace.go:171","msg":"trace[2048574881] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"4.159414492s","start":"2026-02-26T20:56:22.336508Z","end":"2026-02-26T20:56:26.495923Z","steps":["trace[2048574881] 'process raft request' (duration: 4.156089694s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.496098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.33649Z","time spent":"4.159576293s","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:56:26.49636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.517112763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:56:26.496392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.232217337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:56:26.497011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407897478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:26.497061Z","caller":"traceutil/trace.go:171","msg":"trace[1882050470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26; }","duration":"1.407965949s","start":"2026-02-26T20:56:25.089073Z","end":"2026-02-26T20:56:26.497039Z","steps":["trace[1882050470] 'agreement among raft nodes before linearized reading' (duration: 1.407359454s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:25.089054Z","time spent":"1.408031829s","remote":"127.0.0.1:44148","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-02-26T20:56:26.497274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.519325572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:26.497311Z","caller":"traceutil/trace.go:171","msg":"trace[113579170] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26; }","duration":"3.519381592s","start":"2026-02-26T20:56:22.97792Z","end":"2026-02-26T20:56:26.497301Z","steps":["trace[113579170] 'agreement among raft nodes before linearized reading' (duration: 3.518490714s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.977905Z","time spent":"3.519421582s","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-26T20:56:26.497399Z","caller":"traceutil/trace.go:171","msg":"trace[978781220] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:26; }","duration":"4.232309148s","start":"2026-02-26T20:56:22.264103Z","end":"2026-02-26T20:56:26.496412Z","steps":["trace[978781220] 'agreement among raft nodes before linearized reading' (duration: 4.232184596s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.264073Z","time spent":"4.233364017s","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-26T20:56:26.49763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.140971077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:26.497731Z","caller":"traceutil/trace.go:171","msg":"trace[209409840] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:26; }","duration":"4.141077058s","start":"2026-02-26T20:56:22.356582Z","end":"2026-02-26T20:56:26.497659Z","steps":["trace[209409840] 'agreement among raft nodes before linearized reading' (duration: 4.139831507s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.249868058s","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-02-26T20:56:26.49789Z","caller":"traceutil/trace.go:171","msg":"trace[1062836381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:26; }","duration":"3.249925109s","start":"2026-02-26T20:56:23.24795Z","end":"2026-02-26T20:56:26.497875Z","steps":["trace[1062836381] 'agreement among raft nodes before linearized reading' (duration: 3.248489307s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.249903638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:56:26.497917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:23.247934Z","time spent":"3.249976379s","remote":"127.0.0.1:44444","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-02-26T20:56:26.497938Z","caller":"traceutil/trace.go:171","msg":"trace[1770079004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:26; }","duration":"3.249963669s","start":"2026-02-26T20:56:23.247959Z","end":"2026-02-26T20:56:26.497923Z","steps":["trace[1770079004] 'agreement among raft nodes before linearized reading' (duration: 3.248477207s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.497972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:23.247946Z","time spent":"3.250019319s","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:56:26.497929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.35657Z","time spent":"4.141224379s","remote":"127.0.0.1:44596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-26T20:56:26.498035Z","caller":"traceutil/trace.go:171","msg":"trace[747542555] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"4.160022859s","start":"2026-02-26T20:56:22.336428Z","end":"2026-02-26T20:56:26.49645Z","steps":["trace[747542555] 'process raft request' (duration: 4.154142917s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:26.4964Z","caller":"traceutil/trace.go:171","msg":"trace[585087388] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:26; }","duration":"3.517180733s","start":"2026-02-26T20:56:22.979203Z","end":"2026-02-26T20:56:26.496384Z","steps":["trace[585087388] 'agreement among raft nodes before linearized reading' (duration: 3.51697074s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.498202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.979178Z","time spent":"3.519016398s","remote":"127.0.0.1:44564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-26T20:56:26.498293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.336411Z","time spent":"4.161761953s","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:56:26.502802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.159852357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:56:26.502928Z","caller":"traceutil/trace.go:171","msg":"trace[908333304] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:26; }","duration":"4.165571307s","start":"2026-02-26T20:56:22.337332Z","end":"2026-02-26T20:56:26.502904Z","steps":["trace[908333304] 'agreement among raft nodes before linearized reading' (duration: 4.159844927s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:26.503027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:22.337324Z","time spent":"4.165688357s","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-02-26T20:56:54.666841Z","caller":"traceutil/trace.go:171","msg":"trace[1419654051] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"128.781343ms","start":"2026-02-26T20:56:54.538025Z","end":"2026-02-26T20:56:54.666807Z","steps":["trace[1419654051] 'process raft request' (duration: 128.48368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:55.376529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190920795,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T20:56:55.455153Z","caller":"traceutil/trace.go:171","msg":"trace[262126199] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"579.550106ms","start":"2026-02-26T20:56:54.875586Z","end":"2026-02-26T20:56:55.455136Z","steps":["trace[262126199] 'read index received' (duration: 579.411305ms)","trace[262126199] 'applied index is now lower than readState.Index' (duration: 135.851µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:56:55.455272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.669957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-26T20:56:55.455298Z","caller":"traceutil/trace.go:171","msg":"trace[81838363] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:512; }","duration":"579.740607ms","start":"2026-02-26T20:56:54.875552Z","end":"2026-02-26T20:56:55.455293Z","steps":["trace[81838363] 'agreement among raft nodes before linearized reading' (duration: 579.649756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:55.455319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:54.875537Z","time spent":"579.776127ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-26T20:56:55.455303Z","caller":"traceutil/trace.go:171","msg":"trace[1992607893] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"697.472958ms","start":"2026-02-26T20:56:54.757728Z","end":"2026-02-26T20:56:55.455201Z","steps":["trace[1992607893] 'process raft request' (duration: 697.323907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:55.455448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:54.75771Z","time spent":"697.64424ms","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:56:56.206277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190920797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:56:56.649743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185221427s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:56:56.650058Z","caller":"traceutil/trace.go:171","msg":"trace[1011002608] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"951.633867ms","start":"2026-02-26T20:56:55.698404Z","end":"2026-02-26T20:56:56.650038Z","steps":["trace[1011002608] 'read index received' (duration: 951.441138ms)","trace[1011002608] 'applied index is now lower than readState.Index' (duration: 191.959µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:56:56.650175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.757341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:56:56.650212Z","caller":"traceutil/trace.go:171","msg":"trace[288168788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"951.839483ms","start":"2026-02-26T20:56:55.698361Z","end":"2026-02-26T20:56:56.650201Z","steps":["trace[288168788] 'agreement among raft nodes before linearized reading' (duration: 951.733899ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:56.650192Z","caller":"traceutil/trace.go:171","msg":"trace[843589196] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.185787287s","start":"2026-02-26T20:56:55.46438Z","end":"2026-02-26T20:56:56.650167Z","steps":["trace[843589196] 'process raft request' (duration: 1.185507774s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:56:56.650247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:55.69834Z","time spent":"951.897785ms","remote":"127.0.0.1:44136","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-02-26T20:56:56.650334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:56:55.464277Z","time spent":"1.185971661s","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:56:56.919007Z","caller":"traceutil/trace.go:171","msg":"trace[877765309] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"238.451291ms","start":"2026-02-26T20:56:56.68053Z","end":"2026-02-26T20:56:56.918981Z","steps":["trace[877765309] 'process raft request' (duration: 237.048991ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:56.923455Z","caller":"traceutil/trace.go:171","msg":"trace[347518724] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"240.955555ms","start":"2026-02-26T20:56:56.68248Z","end":"2026-02-26T20:56:56.923436Z","steps":["trace[347518724] 'process raft request' (duration: 240.717506ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:56:56.923637Z","caller":"traceutil/trace.go:171","msg":"trace[1707107472] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"204.24554ms","start":"2026-02-26T20:56:56.719374Z","end":"2026-02-26T20:56:56.923619Z","steps":["trace[1707107472] 'process raft request' (duration: 203.957828ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:57:03.32911Z","caller":"traceutil/trace.go:171","msg":"trace[1896483152] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"385.449334ms","start":"2026-02-26T20:57:02.943635Z","end":"2026-02-26T20:57:03.329085Z","steps":["trace[1896483152] 'read index received' (duration: 382.982498ms)","trace[1896483152] 'applied index is now lower than readState.Index' (duration: 2.465386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:57:03.329435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.61347ms","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-26T20:57:03.329413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.56616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T20:57:03.329416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.746745ms","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-02-26T20:57:03.329495Z","caller":"traceutil/trace.go:171","msg":"trace[15696371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"328.673535ms","start":"2026-02-26T20:57:03.0008Z","end":"2026-02-26T20:57:03.329474Z","steps":["trace[15696371] 'agreement among raft nodes before linearized reading' (duration: 328.440776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:03.32953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:03.000793Z","time spent":"328.731046ms","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T20:57:03.329527Z","caller":"traceutil/trace.go:171","msg":"trace[553848146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"385.920462ms","start":"2026-02-26T20:57:02.943585Z","end":"2026-02-26T20:57:03.329506Z","steps":["trace[553848146] 'agreement among raft nodes before linearized reading' (duration: 385.60371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:03.32959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:02.943564Z","time spent":"386.017816ms","remote":"127.0.0.1:44444","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-26T20:57:03.329466Z","caller":"traceutil/trace.go:171","msg":"trace[1154593068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"332.703073ms","start":"2026-02-26T20:57:02.996752Z","end":"2026-02-26T20:57:03.329455Z","steps":["trace[1154593068] 'agreement among raft nodes before linearized reading' (duration: 332.468604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:03.3297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:02.996738Z","time spent":"332.955413ms","remote":"127.0.0.1:44444","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-26T20:57:04.808994Z","caller":"traceutil/trace.go:171","msg":"trace[256245090] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"109.056344ms","start":"2026-02-26T20:57:04.699907Z","end":"2026-02-26T20:57:04.808963Z","steps":["trace[256245090] 'read index received' (duration: 108.828655ms)","trace[256245090] 'applied index is now lower than readState.Index' (duration: 226.049µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:57:04.809116Z","caller":"traceutil/trace.go:171","msg":"trace[662536103] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"430.679091ms","start":"2026-02-26T20:57:04.378397Z","end":"2026-02-26T20:57:04.809076Z","steps":["trace[662536103] 'process raft request' (duration: 430.346758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:04.809154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.22284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:57:04.809215Z","caller":"traceutil/trace.go:171","msg":"trace[247301031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"109.343055ms","start":"2026-02-26T20:57:04.699857Z","end":"2026-02-26T20:57:04.8092Z","steps":["trace[247301031] 'agreement among raft nodes before linearized reading' (duration: 109.230481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:04.809305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:04.378379Z","time spent":"430.822556ms","remote":"127.0.0.1:44342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:57:05.994096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190920897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:57:06.494847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200565190920897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:57:06.666621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321894496s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:57:06.667115Z","caller":"traceutil/trace.go:171","msg":"trace[758374334] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.3225545s","start":"2026-02-26T20:57:05.344517Z","end":"2026-02-26T20:57:06.667071Z","steps":["trace[758374334] 'process raft request' (duration: 1.322385744s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:06.667342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:05.344495Z","time spent":"1.322724297s","remote":"127.0.0.1:44444","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-26T20:57:08.331923Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.665055343s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:57:08.332322Z","caller":"traceutil/trace.go:171","msg":"trace[110210838] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:579; }","duration":"2.838950875s","start":"2026-02-26T20:57:05.493349Z","end":"2026-02-26T20:57:08.3323Z","steps":["trace[110210838] 'read index received' (duration: 1.173527969s)","trace[110210838] 'applied index is now lower than readState.Index' (duration: 1.665421816s)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:57:08.332498Z","caller":"traceutil/trace.go:171","msg":"trace[831113602] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"2.98727437s","start":"2026-02-26T20:57:05.345185Z","end":"2026-02-26T20:57:08.332459Z","steps":["trace[831113602] 'process raft request' (duration: 2.987082073s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:57:08.332444Z","caller":"traceutil/trace.go:171","msg":"trace[1372433011] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"2.98728949s","start":"2026-02-26T20:57:05.34505Z","end":"2026-02-26T20:57:08.33234Z","steps":["trace[1372433011] 'process raft request' (duration: 2.987100853s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:08.332685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:05.345161Z","time spent":"2.987406135s","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:57:08.332919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.839529436s","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-26T20:57:08.333039Z","caller":"traceutil/trace.go:171","msg":"trace[1658507333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"2.839698833s","start":"2026-02-26T20:57:05.49331Z","end":"2026-02-26T20:57:08.333009Z","steps":["trace[1658507333] 'agreement among raft nodes before linearized reading' (duration: 2.839158563s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:08.333089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:05.4933Z","time spent":"2.839778675s","remote":"127.0.0.1:44444","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-26T20:57:08.332888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:05.345033Z","time spent":"2.987655934s","remote":"127.0.0.1:44444","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-26T20:57:08.335378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636716166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:57:08.335445Z","caller":"traceutil/trace.go:171","msg":"trace[1426463725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"1.63682158s","start":"2026-02-26T20:57:06.698603Z","end":"2026-02-26T20:57:08.335425Z","steps":["trace[1426463725] 'agreement among raft nodes before linearized reading' (duration: 1.636648974s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:08.335481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:06.698584Z","time spent":"1.636889914s","remote":"127.0.0.1:44136","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-02-26T20:57:08.335753Z","caller":"traceutil/trace.go:171","msg":"trace[781033017] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.278160345s","start":"2026-02-26T20:57:07.057582Z","end":"2026-02-26T20:57:08.335743Z","steps":["trace[781033017] 'process raft request' (duration: 1.277526822s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:08.335833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:07.057512Z","time spent":"1.27827521s","remote":"127.0.0.1:44444","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-02-26T20:57:08.336043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044051687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T20:57:08.336078Z","caller":"traceutil/trace.go:171","msg":"trace[1544632704] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:562; }","duration":"1.044119769s","start":"2026-02-26T20:57:07.291949Z","end":"2026-02-26T20:57:08.336069Z","steps":["trace[1544632704] 'agreement among raft nodes before linearized reading' (duration: 1.043995524s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:08.336106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:07.291927Z","time spent":"1.044171571s","remote":"127.0.0.1:44200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-02-26T20:57:10.285308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.50808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:57:10.285395Z","caller":"traceutil/trace.go:171","msg":"trace[1352168298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"585.655945ms","start":"2026-02-26T20:57:09.69972Z","end":"2026-02-26T20:57:10.285376Z","steps":["trace[1352168298] 'range keys from in-memory index tree' (duration: 585.410005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:10.285444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:09.699695Z","time spent":"585.740157ms","remote":"127.0.0.1:44136","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-02-26T20:57:10.285643Z","caller":"traceutil/trace.go:171","msg":"trace[1237499281] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"349.428032ms","start":"2026-02-26T20:57:09.936179Z","end":"2026-02-26T20:57:10.285607Z","steps":["trace[1237499281] 'process raft request' (duration: 347.678369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:57:10.285819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:57:09.936149Z","time spent":"349.588848ms","remote":"127.0.0.1:44444","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-26T20:57:11.609059Z","caller":"traceutil/trace.go:171","msg":"trace[399054593] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"197.274542ms","start":"2026-02-26T20:57:11.411743Z","end":"2026-02-26T20:57:11.609018Z","steps":["trace[399054593] 'process raft request' (duration: 165.675512ms)","trace[399054593] 'compare' (duration: 31.355872ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:57:19.335831Z","caller":"traceutil/trace.go:171","msg":"trace[1400550447] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"122.771978ms","start":"2026-02-26T20:57:19.213029Z","end":"2026-02-26T20:57:19.335801Z","steps":["trace[1400550447] 'process raft request' (duration: 116.151743ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:58:10.88455Z","caller":"traceutil/trace.go:171","msg":"trace[2076221983] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"105.304951ms","start":"2026-02-26T20:58:10.779225Z","end":"2026-02-26T20:58:10.88453Z","steps":["trace[2076221983] 'process raft request' (duration: 103.453353ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:58:10.887865Z","caller":"traceutil/trace.go:171","msg":"trace[1095777291] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"107.923395ms","start":"2026-02-26T20:58:10.77989Z","end":"2026-02-26T20:58:10.887814Z","steps":["trace[1095777291] 'process raft request' (duration: 107.786572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:58:41.541527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.378932ms","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-26T20:58:41.541699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.839604ms","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-02-26T20:58:41.541529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.734465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-26T20:58:41.541691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.224239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:58:41.541755Z","caller":"traceutil/trace.go:171","msg":"trace[1546921945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"303.985539ms","start":"2026-02-26T20:58:41.237759Z","end":"2026-02-26T20:58:41.541744Z","steps":["trace[1546921945] 'range keys from in-memory index tree' (duration: 303.539261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:58:41.541784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:58:41.237753Z","time spent":"304.02183ms","remote":"127.0.0.1:44444","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":"info","ts":"2026-02-26T20:58:41.541774Z","caller":"traceutil/trace.go:171","msg":"trace[562282110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"373.934135ms","start":"2026-02-26T20:58:41.167811Z","end":"2026-02-26T20:58:41.541745Z","steps":["trace[562282110] 'range keys from in-memory index tree' (duration: 373.697092ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:58:41.541783Z","caller":"traceutil/trace.go:171","msg":"trace[156591585] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1007; }","duration":"219.373672ms","start":"2026-02-26T20:58:41.322392Z","end":"2026-02-26T20:58:41.541765Z","steps":["trace[156591585] 'count revisions from in-memory index tree' (duration: 219.148788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:58:41.541786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.906361ms","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-26T20:58:41.541838Z","caller":"traceutil/trace.go:171","msg":"trace[945652954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"309.012523ms","start":"2026-02-26T20:58:41.232811Z","end":"2026-02-26T20:58:41.541824Z","steps":["trace[945652954] 'range keys from in-memory index tree' (duration: 308.739348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:58:41.541872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:58:41.23279Z","time spent":"309.073844ms","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T20:58:41.541716Z","caller":"traceutil/trace.go:171","msg":"trace[688331561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"110.610636ms","start":"2026-02-26T20:58:41.431088Z","end":"2026-02-26T20:58:41.541699Z","steps":["trace[688331561] 'range keys from in-memory index tree' (duration: 110.25855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:58:41.54187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:58:41.167793Z","time spent":"374.014257ms","remote":"127.0.0.1:44444","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-02-26T20:58:41.723699Z","caller":"traceutil/trace.go:171","msg":"trace[220709483] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"176.78195ms","start":"2026-02-26T20:58:41.546893Z","end":"2026-02-26T20:58:41.723675Z","steps":["trace[220709483] 'process raft request' (duration: 176.601107ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:58:41.723904Z","caller":"traceutil/trace.go:171","msg":"trace[1317577242] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"176.985753ms","start":"2026-02-26T20:58:41.54691Z","end":"2026-02-26T20:58:41.723896Z","steps":["trace[1317577242] 'process raft request' (duration: 176.733669ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:58:41.72416Z","caller":"traceutil/trace.go:171","msg":"trace[257624456] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"177.209157ms","start":"2026-02-26T20:58:41.546929Z","end":"2026-02-26T20:58:41.724138Z","steps":["trace[257624456] 'process raft request' (duration: 176.939432ms)"],"step_count":1}