{"level":"warn","ts":"2026-02-28T01:25:51.286467Z","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-28T01:25:51.286559Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-28T01:25:51.286682Z","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-28T01:25:51.286702Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-28T01:25:51.286753Z","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-28T01:25:51.287509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-02-28T01:25:51.287625Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-28T01:25:51.296221Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.826882ms"} {"level":"info","ts":"2026-02-28T01:25:51.305245Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-02-28T01:25:51.305315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T01:25:51.305334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-02-28T01:25:51.305344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-28T01:25:51.30536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-02-28T01:25:51.305401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-02-28T01:25:51.312756Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T01:25:51.314616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T01:25:51.315947Z","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-28T01:25:51.320523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-28T01:25:51.320709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-28T01:25:51.32093Z","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-28T01:25:51.320993Z","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-28T01:25:51.321004Z","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-28T01:25:51.322749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-02-28T01:25:51.323141Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-28T01:25:51.325775Z","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-28T01:25:51.325851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-28T01:25:51.325918Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-28T01:25:51.32617Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-28T01:25:51.326216Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T01:25:51.506058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T01:25:51.506158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T01:25:51.506197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-02-28T01:25:51.506215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-02-28T01:25:51.506226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-28T01:25:51.506245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-02-28T01:25:51.506271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-28T01:25:51.507673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:25:51.507949Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-02-28T01:25:51.508016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:25:51.50805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:25:51.509454Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:25:51.509523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:25:51.509544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:25:51.509784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-28T01:25:51.510116Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T01:25:51.510137Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T01:25:51.511699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-02-28T01:25:54.383649Z","caller":"traceutil/trace.go:171","msg":"trace[1621103364] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"119.34009ms","start":"2026-02-28T01:25:54.264286Z","end":"2026-02-28T01:25:54.383626Z","steps":["trace[1621103364] 'process raft request' (duration: 111.870805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:55.191393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.826587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-28T01:25:55.191587Z","caller":"traceutil/trace.go:171","msg":"trace[165067999] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"212.511893ms","start":"2026-02-28T01:25:54.979059Z","end":"2026-02-28T01:25:55.191571Z","steps":["trace[165067999] 'read index received' (duration: 29.021µs)","trace[165067999] 'applied index is now lower than readState.Index' (duration: 212.481352ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:55.191608Z","caller":"traceutil/trace.go:171","msg":"trace[57601421] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"798.445346ms","start":"2026-02-28T01:25:54.393139Z","end":"2026-02-28T01:25:55.191584Z","steps":["trace[57601421] 'process raft request' (duration: 138.981383ms)","trace[57601421] 'compare' (duration: 658.702246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:25:55.191701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:54.393128Z","time spent":"798.530826ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:25:55.191812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.751324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:25:55.191872Z","caller":"traceutil/trace.go:171","msg":"trace[81401833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"212.852955ms","start":"2026-02-28T01:25:54.979009Z","end":"2026-02-28T01:25:55.191862Z","steps":["trace[81401833] 'agreement among raft nodes before linearized reading' (duration: 212.629623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:56.408114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097595995s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-28T01:25:56.408318Z","caller":"traceutil/trace.go:171","msg":"trace[231796068] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"1.159585544s","start":"2026-02-28T01:25:55.248693Z","end":"2026-02-28T01:25:56.408278Z","steps":["trace[231796068] 'process raft request' (duration: 61.746877ms)","trace[231796068] 'compare' (duration: 1.097442724s)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:56.408401Z","caller":"traceutil/trace.go:171","msg":"trace[1610985272] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"1.109559479s","start":"2026-02-28T01:25:55.298788Z","end":"2026-02-28T01:25:56.408347Z","steps":["trace[1610985272] 'process raft request' (duration: 1.109493278s)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:25:56.408416Z","caller":"traceutil/trace.go:171","msg":"trace[804820937] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"1.121024589s","start":"2026-02-28T01:25:55.287355Z","end":"2026-02-28T01:25:56.408379Z","steps":["trace[804820937] 'read index received' (duration: 23.091281ms)","trace[804820937] 'applied index is now lower than readState.Index' (duration: 1.097929488s)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:25:56.408485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:55.298757Z","time spent":"1.109700811s","remote":"127.0.0.1:40938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:25:56.408521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:55.248678Z","time spent":"1.159764637s","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:25:56.408611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121230601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:25:56.40864Z","caller":"traceutil/trace.go:171","msg":"trace[1964614277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:114; }","duration":"1.121294381s","start":"2026-02-28T01:25:55.287338Z","end":"2026-02-28T01:25:56.408632Z","steps":["trace[1964614277] 'agreement among raft nodes before linearized reading' (duration: 1.121181981s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:56.408719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:55.287331Z","time spent":"1.121380862s","remote":"127.0.0.1:41030","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-28T01:25:56.409026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.264696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:25:56.409103Z","caller":"traceutil/trace.go:171","msg":"trace[1555691996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"329.372106ms","start":"2026-02-28T01:25:56.079707Z","end":"2026-02-28T01:25:56.409079Z","steps":["trace[1555691996] 'agreement among raft nodes before linearized reading' (duration: 328.996163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:56.40915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.079685Z","time spent":"329.457008ms","remote":"127.0.0.1:40760","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-28T01:25:56.913667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953031151962680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:25:57.414869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953031151962680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:25:57.915437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953031151962680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:25:58.416454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953031151962680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:25:58.657222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.244911109s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-28T01:25:58.821327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.852173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-28T01:25:58.821609Z","caller":"traceutil/trace.go:171","msg":"trace[815773910] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"2.409319445s","start":"2026-02-28T01:25:56.41225Z","end":"2026-02-28T01:25:58.821569Z","steps":["trace[815773910] 'process raft request' (duration: 2.24516578s)","trace[815773910] 'compare' (duration: 162.878615ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:58.82169Z","caller":"traceutil/trace.go:171","msg":"trace[1229794986] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:118; }","duration":"2.40877865s","start":"2026-02-28T01:25:56.412894Z","end":"2026-02-28T01:25:58.821673Z","steps":["trace[1229794986] 'read index received' (duration: 2.244533444s)","trace[1229794986] 'applied index is now lower than readState.Index' (duration: 164.243946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:25:58.82176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.412239Z","time spent":"2.409447476s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T01:25:58.821867Z","caller":"traceutil/trace.go:171","msg":"trace[2014704742] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"1.931246689s","start":"2026-02-28T01:25:56.89061Z","end":"2026-02-28T01:25:58.821857Z","steps":["trace[2014704742] 'process raft request' (duration: 1.930821815s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:58.821933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.890585Z","time spent":"1.931319289s","remote":"127.0.0.1:40938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:25:58.822129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.409228425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-28T01:25:58.822135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.074057248s","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-28T01:25:58.822179Z","caller":"traceutil/trace.go:171","msg":"trace[892087708] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:0; response_revision:116; }","duration":"2.409292765s","start":"2026-02-28T01:25:56.412875Z","end":"2026-02-28T01:25:58.822167Z","steps":["trace[892087708] 'agreement among raft nodes before linearized reading' (duration: 2.409212425s)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:25:58.822204Z","caller":"traceutil/trace.go:171","msg":"trace[328357049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:116; }","duration":"2.074134138s","start":"2026-02-28T01:25:56.74805Z","end":"2026-02-28T01:25:58.822184Z","steps":["trace[328357049] 'agreement among raft nodes before linearized reading' (duration: 2.074018537s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:58.822247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.961321Z","time spent":"1.860925243s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-28T01:25:58.822254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.747994Z","time spent":"2.07425232s","remote":"127.0.0.1:41030","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":"warn","ts":"2026-02-28T01:25:58.822219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:56.412869Z","time spent":"2.409343006s","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-02-28T01:25:58.822426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.016727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:25:58.822476Z","caller":"traceutil/trace.go:171","msg":"trace[69241188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"354.138588ms","start":"2026-02-28T01:25:58.468326Z","end":"2026-02-28T01:25:58.822465Z","steps":["trace[69241188] 'agreement among raft nodes before linearized reading' (duration: 354.085367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:58.822504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:58.468303Z","time spent":"354.195608ms","remote":"127.0.0.1:40760","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-28T01:25:58.822388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643240604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-28T01:25:58.822654Z","caller":"traceutil/trace.go:171","msg":"trace[945829840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"1.643522826s","start":"2026-02-28T01:25:57.17912Z","end":"2026-02-28T01:25:58.822643Z","steps":["trace[945829840] 'agreement among raft nodes before linearized reading' (duration: 1.643212064s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:58.822695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:25:57.17905Z","time spent":"1.643635227s","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T01:25:59.089074Z","caller":"traceutil/trace.go:171","msg":"trace[383449923] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"264.752297ms","start":"2026-02-28T01:25:58.824299Z","end":"2026-02-28T01:25:59.089051Z","steps":["trace[383449923] 'process raft request' (duration: 185.368584ms)","trace[383449923] 'compare' (duration: 79.111721ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:59.089068Z","caller":"traceutil/trace.go:171","msg":"trace[729035614] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"263.434616ms","start":"2026-02-28T01:25:58.825597Z","end":"2026-02-28T01:25:59.089032Z","steps":["trace[729035614] 'process raft request' (duration: 263.335095ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:25:59.089392Z","caller":"traceutil/trace.go:171","msg":"trace[1123703431] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"262.65082ms","start":"2026-02-28T01:25:58.826531Z","end":"2026-02-28T01:25:59.089181Z","steps":["trace[1123703431] 'process raft request' (duration: 262.443498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:25:59.089606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.548257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-28T01:25:59.089643Z","caller":"traceutil/trace.go:171","msg":"trace[1751862564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:119; }","duration":"263.632488ms","start":"2026-02-28T01:25:58.826Z","end":"2026-02-28T01:25:59.089632Z","steps":["trace[1751862564] 'agreement among raft nodes before linearized reading' (duration: 263.481347ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:25:59.089438Z","caller":"traceutil/trace.go:171","msg":"trace[555830287] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:121; }","duration":"262.968772ms","start":"2026-02-28T01:25:58.826039Z","end":"2026-02-28T01:25:59.089008Z","steps":["trace[555830287] 'read index received' (duration: 183.590509ms)","trace[555830287] 'applied index is now lower than readState.Index' (duration: 79.377153ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:59.851312Z","caller":"traceutil/trace.go:171","msg":"trace[1419645146] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"115.827847ms","start":"2026-02-28T01:25:59.735454Z","end":"2026-02-28T01:25:59.851282Z","steps":["trace[1419645146] 'process raft request' (duration: 85.691036ms)","trace[1419645146] 'compare' (duration: 29.98498ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:25:59.88969Z","caller":"traceutil/trace.go:171","msg":"trace[97778138] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"152.538644ms","start":"2026-02-28T01:25:59.737124Z","end":"2026-02-28T01:25:59.889663Z","steps":["trace[97778138] 'process raft request' (duration: 152.263352ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:25:59.889738Z","caller":"traceutil/trace.go:171","msg":"trace[1200231648] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"122.621904ms","start":"2026-02-28T01:25:59.767083Z","end":"2026-02-28T01:25:59.889705Z","steps":["trace[1200231648] 'process raft request' (duration: 122.486873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:00.569524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.658382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:26:00.56965Z","caller":"traceutil/trace.go:171","msg":"trace[2022013677] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:169; }","duration":"456.786189ms","start":"2026-02-28T01:26:00.112845Z","end":"2026-02-28T01:26:00.569631Z","steps":["trace[2022013677] 'read index received' (duration: 24.751304ms)","trace[2022013677] 'applied index is now lower than readState.Index' (duration: 432.033725ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:00.569714Z","caller":"traceutil/trace.go:171","msg":"trace[1389939405] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"457.103133ms","start":"2026-02-28T01:26:00.112568Z","end":"2026-02-28T01:26:00.569671Z","steps":["trace[1389939405] 'process raft request' (duration: 25.028777ms)","trace[1389939405] 'compare' (duration: 431.524981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:00.569885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:00.112542Z","time spent":"457.249414ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:26:00.569882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.012292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:00.569962Z","caller":"traceutil/trace.go:171","msg":"trace[1761436186] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:0; response_revision:165; }","duration":"457.110133ms","start":"2026-02-28T01:26:00.112831Z","end":"2026-02-28T01:26:00.569941Z","steps":["trace[1761436186] 'agreement among raft nodes before linearized reading' (duration: 456.919011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:00.570018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:00.112789Z","time spent":"457.220103ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" "} {"level":"info","ts":"2026-02-28T01:26:03.832328Z","caller":"traceutil/trace.go:171","msg":"trace[891806045] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"106.839179ms","start":"2026-02-28T01:26:03.72546Z","end":"2026-02-28T01:26:03.832299Z","steps":["trace[891806045] 'read index received' (duration: 103.744665ms)","trace[891806045] 'applied index is now lower than readState.Index' (duration: 3.093314ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:03.83243Z","caller":"traceutil/trace.go:171","msg":"trace[900049874] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"124.263739ms","start":"2026-02-28T01:26:03.708131Z","end":"2026-02-28T01:26:03.832394Z","steps":["trace[900049874] 'process raft request' (duration: 121.058444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:03.832527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.047491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:03.832566Z","caller":"traceutil/trace.go:171","msg":"trace[1878093542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:300; }","duration":"107.138302ms","start":"2026-02-28T01:26:03.725415Z","end":"2026-02-28T01:26:03.832553Z","steps":["trace[1878093542] 'agreement among raft nodes before linearized reading' (duration: 107.031351ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:04.897954Z","caller":"traceutil/trace.go:171","msg":"trace[1710286745] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"123.329064ms","start":"2026-02-28T01:26:04.774596Z","end":"2026-02-28T01:26:04.897925Z","steps":["trace[1710286745] 'read index received' (duration: 123.281434ms)","trace[1710286745] 'applied index is now lower than readState.Index' (duration: 46.47µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:04.897977Z","caller":"traceutil/trace.go:171","msg":"trace[1234284007] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"124.953767ms","start":"2026-02-28T01:26:04.772998Z","end":"2026-02-28T01:26:04.897952Z","steps":["trace[1234284007] 'process raft request' (duration: 124.831606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:04.898098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.473825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:04.898131Z","caller":"traceutil/trace.go:171","msg":"trace[9314995] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:326; }","duration":"123.568156ms","start":"2026-02-28T01:26:04.774556Z","end":"2026-02-28T01:26:04.898124Z","steps":["trace[9314995] 'agreement among raft nodes before linearized reading' (duration: 123.475775ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:04.925094Z","caller":"traceutil/trace.go:171","msg":"trace[640868715] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"149.901195ms","start":"2026-02-28T01:26:04.775177Z","end":"2026-02-28T01:26:04.925078Z","steps":["trace[640868715] 'process raft request' (duration: 149.692164ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:04.925245Z","caller":"traceutil/trace.go:171","msg":"trace[1234748564] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"136.163337ms","start":"2026-02-28T01:26:04.789057Z","end":"2026-02-28T01:26:04.925221Z","steps":["trace[1234748564] 'process raft request' (duration: 135.942825ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:05.283164Z","caller":"traceutil/trace.go:171","msg":"trace[357017566] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"113.168715ms","start":"2026-02-28T01:26:05.169963Z","end":"2026-02-28T01:26:05.283132Z","steps":["trace[357017566] 'read index received' (duration: 15.664334ms)","trace[357017566] 'applied index is now lower than readState.Index' (duration: 97.503411ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:05.283264Z","caller":"traceutil/trace.go:171","msg":"trace[260531936] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"151.234136ms","start":"2026-02-28T01:26:05.131995Z","end":"2026-02-28T01:26:05.283229Z","steps":["trace[260531936] 'process raft request' (duration: 53.643534ms)","trace[260531936] 'compare' (duration: 97.35144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:05.28336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.468717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-28T01:26:05.283404Z","caller":"traceutil/trace.go:171","msg":"trace[151788508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:332; }","duration":"108.564789ms","start":"2026-02-28T01:26:05.174827Z","end":"2026-02-28T01:26:05.283392Z","steps":["trace[151788508] 'agreement among raft nodes before linearized reading' (duration: 108.471378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:05.283344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.359566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-28T01:26:05.283796Z","caller":"traceutil/trace.go:171","msg":"trace[547931681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"113.856231ms","start":"2026-02-28T01:26:05.169917Z","end":"2026-02-28T01:26:05.283773Z","steps":["trace[547931681] 'agreement among raft nodes before linearized reading' (duration: 113.327337ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:26.633835Z","caller":"traceutil/trace.go:171","msg":"trace[1456818307] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"137.421415ms","start":"2026-02-28T01:26:26.49639Z","end":"2026-02-28T01:26:26.633811Z","steps":["trace[1456818307] 'process raft request' (duration: 137.278114ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:27.59063Z","caller":"traceutil/trace.go:171","msg":"trace[847727205] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"487.942957ms","start":"2026-02-28T01:26:27.102651Z","end":"2026-02-28T01:26:27.590594Z","steps":["trace[847727205] 'read index received' (duration: 479.394059ms)","trace[847727205] 'applied index is now lower than readState.Index' (duration: 8.547258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:27.590883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.666473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:27.590949Z","caller":"traceutil/trace.go:171","msg":"trace[1173615286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"222.750164ms","start":"2026-02-28T01:26:27.368182Z","end":"2026-02-28T01:26:27.590932Z","steps":["trace[1173615286] 'agreement among raft nodes before linearized reading' (duration: 222.628713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:27.590914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.581717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-28T01:26:27.590979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.31706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-28T01:26:27.591053Z","caller":"traceutil/trace.go:171","msg":"trace[1779094647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"154.775038ms","start":"2026-02-28T01:26:27.436259Z","end":"2026-02-28T01:26:27.591034Z","steps":["trace[1779094647] 'agreement among raft nodes before linearized reading' (duration: 154.560167ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:27.591166Z","caller":"traceutil/trace.go:171","msg":"trace[1752733304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"488.486671ms","start":"2026-02-28T01:26:27.102595Z","end":"2026-02-28T01:26:27.591081Z","steps":["trace[1752733304] 'agreement among raft nodes before linearized reading' (duration: 488.143298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:27.591247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.285289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-28T01:26:27.591221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:27.102572Z","time spent":"488.637242ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-28T01:26:27.591291Z","caller":"traceutil/trace.go:171","msg":"trace[848999885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"213.345669ms","start":"2026-02-28T01:26:27.377935Z","end":"2026-02-28T01:26:27.591281Z","steps":["trace[848999885] 'agreement among raft nodes before linearized reading' (duration: 213.263689ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:31.791177Z","caller":"traceutil/trace.go:171","msg":"trace[1843968330] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"109.899138ms","start":"2026-02-28T01:26:31.681258Z","end":"2026-02-28T01:26:31.791157Z","steps":["trace[1843968330] 'process raft request' (duration: 62.151777ms)","trace[1843968330] 'compare' (duration: 47.332468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:32.965294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.556884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:32.965344Z","caller":"traceutil/trace.go:171","msg":"trace[281017114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"597.650865ms","start":"2026-02-28T01:26:32.367682Z","end":"2026-02-28T01:26:32.965333Z","steps":["trace[281017114] 'range keys from in-memory index tree' (duration: 597.482714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:32.965366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:32.367662Z","time spent":"597.699445ms","remote":"127.0.0.1:40760","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-28T01:26:32.965462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.231764ms","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-28T01:26:32.96555Z","caller":"traceutil/trace.go:171","msg":"trace[1038084374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"316.353815ms","start":"2026-02-28T01:26:32.649184Z","end":"2026-02-28T01:26:32.965538Z","steps":["trace[1038084374] 'range keys from in-memory index tree' (duration: 316.010542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:32.965588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:32.649156Z","time spent":"316.423965ms","remote":"127.0.0.1:41030","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-28T01:26:33.77953Z","caller":"traceutil/trace.go:171","msg":"trace[347277312] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"155.555975ms","start":"2026-02-28T01:26:33.623953Z","end":"2026-02-28T01:26:33.779509Z","steps":["trace[347277312] 'read index received' (duration: 155.440724ms)","trace[347277312] 'applied index is now lower than readState.Index' (duration: 114.361µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:33.779802Z","caller":"traceutil/trace.go:171","msg":"trace[1928443180] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"218.290983ms","start":"2026-02-28T01:26:33.5615Z","end":"2026-02-28T01:26:33.779791Z","steps":["trace[1928443180] 'process raft request' (duration: 217.801639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:33.780075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.118409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T01:26:33.780118Z","caller":"traceutil/trace.go:171","msg":"trace[1690983756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"156.17708ms","start":"2026-02-28T01:26:33.62393Z","end":"2026-02-28T01:26:33.780107Z","steps":["trace[1690983756] 'agreement among raft nodes before linearized reading' (duration: 156.037149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:34.425257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.848777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:26:34.425365Z","caller":"traceutil/trace.go:171","msg":"trace[323749260] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"630.167702ms","start":"2026-02-28T01:26:33.795181Z","end":"2026-02-28T01:26:34.425348Z","steps":["trace[323749260] 'read index received' (duration: 74.119404ms)","trace[323749260] 'applied index is now lower than readState.Index' (duration: 556.046808ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:34.425478Z","caller":"traceutil/trace.go:171","msg":"trace[345450289] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"639.180942ms","start":"2026-02-28T01:26:33.786286Z","end":"2026-02-28T01:26:34.425467Z","steps":["trace[345450289] 'process raft request' (duration: 83.041173ms)","trace[345450289] 'compare' (duration: 555.696926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:34.42554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:33.786234Z","time spent":"639.276312ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:26:34.425693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.3093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-svqsp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-28T01:26:34.42579Z","caller":"traceutil/trace.go:171","msg":"trace[618186849] range","detail":"{range_begin:/registry/pods/kube-system/cilium-svqsp; range_end:; response_count:1; response_revision:555; }","duration":"580.450231ms","start":"2026-02-28T01:26:33.845325Z","end":"2026-02-28T01:26:34.425775Z","steps":["trace[618186849] 'agreement among raft nodes before linearized reading' (duration: 580.222379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:34.425767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.574895ms","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-28T01:26:34.425826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:33.845305Z","time spent":"580.513701ms","remote":"127.0.0.1:40938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-svqsp\" "} {"level":"info","ts":"2026-02-28T01:26:34.42585Z","caller":"traceutil/trace.go:171","msg":"trace[1774867019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"630.668335ms","start":"2026-02-28T01:26:33.79516Z","end":"2026-02-28T01:26:34.425828Z","steps":["trace[1774867019] 'agreement among raft nodes before linearized reading' (duration: 630.428134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:34.4259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:33.795133Z","time spent":"630.750586ms","remote":"127.0.0.1:41030","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-28T01:26:34.79651Z","caller":"traceutil/trace.go:171","msg":"trace[1657642112] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"286.827311ms","start":"2026-02-28T01:26:34.509664Z","end":"2026-02-28T01:26:34.796491Z","steps":["trace[1657642112] 'process raft request' (duration: 286.791861ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:34.79653Z","caller":"traceutil/trace.go:171","msg":"trace[1191536978] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"358.301785ms","start":"2026-02-28T01:26:34.438196Z","end":"2026-02-28T01:26:34.796498Z","steps":["trace[1191536978] 'process raft request' (duration: 358.098504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:34.796696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:34.438175Z","time spent":"358.428476ms","remote":"127.0.0.1:40938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:26:34.946767Z","caller":"traceutil/trace.go:171","msg":"trace[418275653] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"365.899955ms","start":"2026-02-28T01:26:34.580835Z","end":"2026-02-28T01:26:34.946735Z","steps":["trace[418275653] 'process raft request' (duration: 365.752864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:34.946998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:34.580813Z","time spent":"366.105317ms","remote":"127.0.0.1:40930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:26:36.604482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.003871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:36.604541Z","caller":"traceutil/trace.go:171","msg":"trace[1555388439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"236.107216ms","start":"2026-02-28T01:26:36.36842Z","end":"2026-02-28T01:26:36.604528Z","steps":["trace[1555388439] 'range keys from in-memory index tree' (duration: 235.924307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.60466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.172695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T01:26:36.604729Z","caller":"traceutil/trace.go:171","msg":"trace[139997630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"175.315402ms","start":"2026-02-28T01:26:36.429398Z","end":"2026-02-28T01:26:36.604713Z","steps":["trace[139997630] 'range keys from in-memory index tree' (duration: 175.010196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.604828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.421688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:26:36.604934Z","caller":"traceutil/trace.go:171","msg":"trace[551198276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"806.545043ms","start":"2026-02-28T01:26:35.798349Z","end":"2026-02-28T01:26:36.604894Z","steps":["trace[551198276] 'range keys from in-memory index tree' (duration: 806.220611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.605002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:35.798334Z","time spent":"806.652747ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-28T01:26:36.607749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600421915s","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-28T01:26:36.60779Z","caller":"traceutil/trace.go:171","msg":"trace[1516525301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"1.600490208s","start":"2026-02-28T01:26:35.00729Z","end":"2026-02-28T01:26:36.60778Z","steps":["trace[1516525301] 'range keys from in-memory index tree' (duration: 1.59705194s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.607811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:35.007272Z","time spent":"1.600533259s","remote":"127.0.0.1:41030","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-28T01:26:36.81247Z","caller":"traceutil/trace.go:171","msg":"trace[1189558582] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"201.375499ms","start":"2026-02-28T01:26:36.610676Z","end":"2026-02-28T01:26:36.812051Z","steps":["trace[1189558582] 'process raft request' (duration: 201.20007ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:36.923688Z","caller":"traceutil/trace.go:171","msg":"trace[2090288187] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"312.723328ms","start":"2026-02-28T01:26:36.610911Z","end":"2026-02-28T01:26:36.923634Z","steps":["trace[2090288187] 'process raft request' (duration: 310.258875ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:26:36.923751Z","caller":"traceutil/trace.go:171","msg":"trace[1735775414] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"312.179001ms","start":"2026-02-28T01:26:36.611541Z","end":"2026-02-28T01:26:36.92372Z","steps":["trace[1735775414] 'process raft request' (duration: 311.846005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.923937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:36.611519Z","time spent":"312.316518ms","remote":"127.0.0.1:41030","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-28T01:26:36.92394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:36.610905Z","time spent":"312.913337ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:26:36.923942Z","caller":"traceutil/trace.go:171","msg":"trace[98349231] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"312.837504ms","start":"2026-02-28T01:26:36.611077Z","end":"2026-02-28T01:26:36.923914Z","steps":["trace[98349231] 'process raft request' (duration: 312.251785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:36.924081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:36.611034Z","time spent":"312.971241ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:26:38.613075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.61975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:26:38.613196Z","caller":"traceutil/trace.go:171","msg":"trace[1796825141] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"245.868654ms","start":"2026-02-28T01:26:38.367296Z","end":"2026-02-28T01:26:38.613165Z","steps":["trace[1796825141] 'read index received' (duration: 47.422µs)","trace[1796825141] 'applied index is now lower than readState.Index' (duration: 245.819942ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:26:38.613389Z","caller":"traceutil/trace.go:171","msg":"trace[1424356943] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"643.093265ms","start":"2026-02-28T01:26:37.970283Z","end":"2026-02-28T01:26:38.613376Z","steps":["trace[1424356943] 'process raft request' (duration: 218.107657ms)","trace[1424356943] 'compare' (duration: 424.498654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:26:38.613441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:26:37.970255Z","time spent":"643.162259ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:26:38.613547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.240672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:26:38.613626Z","caller":"traceutil/trace.go:171","msg":"trace[1907340107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"246.354777ms","start":"2026-02-28T01:26:38.367251Z","end":"2026-02-28T01:26:38.613606Z","steps":["trace[1907340107] 'agreement among raft nodes before linearized reading' (duration: 246.174709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:26:38.613548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.291317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-28T01:26:38.61371Z","caller":"traceutil/trace.go:171","msg":"trace[144315884] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:569; }","duration":"135.525578ms","start":"2026-02-28T01:26:38.478165Z","end":"2026-02-28T01:26:38.613691Z","steps":["trace[144315884] 'agreement among raft nodes before linearized reading' (duration: 135.264336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:27:57.307012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.64724ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-28T01:27:57.307148Z","caller":"traceutil/trace.go:171","msg":"trace[1278832126] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"237.213758ms","start":"2026-02-28T01:27:57.069889Z","end":"2026-02-28T01:27:57.307103Z","steps":["trace[1278832126] 'read index received' (duration: 4.376654ms)","trace[1278832126] 'applied index is now lower than readState.Index' (duration: 232.835654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:27:57.30737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.582459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-28T01:27:57.307407Z","caller":"traceutil/trace.go:171","msg":"trace[1892521084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"131.663111ms","start":"2026-02-28T01:27:57.175734Z","end":"2026-02-28T01:27:57.307397Z","steps":["trace[1892521084] 'agreement among raft nodes before linearized reading' (duration: 131.495777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:27:57.307825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.910043ms","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-28T01:27:57.307977Z","caller":"traceutil/trace.go:171","msg":"trace[1626378348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"238.073077ms","start":"2026-02-28T01:27:57.069845Z","end":"2026-02-28T01:27:57.307918Z","steps":["trace[1626378348] 'agreement among raft nodes before linearized reading' (duration: 237.328561ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:28:05.869649Z","caller":"traceutil/trace.go:171","msg":"trace[701524561] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"212.92834ms","start":"2026-02-28T01:28:05.656687Z","end":"2026-02-28T01:28:05.869616Z","steps":["trace[701524561] 'process raft request' (duration: 212.738736ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:28:07.42987Z","caller":"traceutil/trace.go:171","msg":"trace[834553069] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"520.420047ms","start":"2026-02-28T01:28:06.909427Z","end":"2026-02-28T01:28:07.429847Z","steps":["trace[834553069] 'process raft request' (duration: 520.06496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:28:07.430072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:28:06.909394Z","time spent":"520.54882ms","remote":"127.0.0.1:41030","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-28T01:28:07.648013Z","caller":"traceutil/trace.go:171","msg":"trace[1640212101] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"278.246422ms","start":"2026-02-28T01:28:07.369731Z","end":"2026-02-28T01:28:07.647977Z","steps":["trace[1640212101] 'read index received' (duration: 60.169373ms)","trace[1640212101] 'applied index is now lower than readState.Index' (duration: 218.076129ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:28:07.648153Z","caller":"traceutil/trace.go:171","msg":"trace[417306219] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"400.126739ms","start":"2026-02-28T01:28:07.248016Z","end":"2026-02-28T01:28:07.648143Z","steps":["trace[417306219] 'process raft request' (duration: 399.843933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:28:07.648318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.431256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-28T01:28:07.648344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:28:07.247976Z","time spent":"400.211261ms","remote":"127.0.0.1:40930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:28:07.648417Z","caller":"traceutil/trace.go:171","msg":"trace[1714889966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"276.539988ms","start":"2026-02-28T01:28:07.371863Z","end":"2026-02-28T01:28:07.648403Z","steps":["trace[1714889966] 'agreement among raft nodes before linearized reading' (duration: 276.351644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:28:07.648553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.488527ms","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-28T01:28:07.648647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.915966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:28:07.648641Z","caller":"traceutil/trace.go:171","msg":"trace[784166416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"230.609689ms","start":"2026-02-28T01:28:07.418013Z","end":"2026-02-28T01:28:07.648622Z","steps":["trace[784166416] 'agreement among raft nodes before linearized reading' (duration: 230.287573ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:28:07.648666Z","caller":"traceutil/trace.go:171","msg":"trace[1431475786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"230.954587ms","start":"2026-02-28T01:28:07.417706Z","end":"2026-02-28T01:28:07.64866Z","steps":["trace[1431475786] 'agreement among raft nodes before linearized reading' (duration: 230.910786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:28:07.648754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.004607ms","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-28T01:28:07.648604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.746513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T01:28:07.648885Z","caller":"traceutil/trace.go:171","msg":"trace[1144933959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"231.063779ms","start":"2026-02-28T01:28:07.417812Z","end":"2026-02-28T01:28:07.648876Z","steps":["trace[1144933959] 'agreement among raft nodes before linearized reading' (duration: 230.749683ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:28:07.64888Z","caller":"traceutil/trace.go:171","msg":"trace[914500329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"279.160341ms","start":"2026-02-28T01:28:07.369673Z","end":"2026-02-28T01:28:07.648834Z","steps":["trace[914500329] 'agreement among raft nodes before linearized reading' (duration: 278.815564ms)"],"step_count":1}