{"level":"warn","ts":"2026-02-25T02:06:02.380381Z","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-25T02:06:02.380579Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-25T02:06:02.380694Z","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-25T02:06:02.380712Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-25T02:06:02.380752Z","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-25T02:06:02.382647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-02-25T02:06:02.383755Z","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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-25T02:06:02.38795Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.861963ms"} {"level":"info","ts":"2026-02-25T02:06:02.396136Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-02-25T02:06:02.396228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:06:02.396251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:06:02.396263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:06:02.396273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:06:02.396315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-02-25T02:06:02.403649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:06:02.404777Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:06:02.405803Z","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-25T02:06:02.407635Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:06:02.407933Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:06:02.408122Z","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-25T02:06:02.408221Z","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-25T02:06:02.408243Z","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-25T02:06:02.41011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-02-25T02:06:02.410471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-25T02:06:02.417554Z","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-25T02:06:02.417726Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-25T02:06:02.417757Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-25T02:06:02.41813Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:06:02.418168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:06:03.09737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:06:03.097414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:06:03.097443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-02-25T02:06:03.097454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:06:03.097463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-25T02:06:03.09747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:06:03.097477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-25T02:06:03.103758Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:06:03.103815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:06:03.103948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:06:03.104081Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:03.107191Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:06:03.10725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:06:03.10871Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:03.108918Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:03.108986Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:03.109219Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:06:03.111765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"warn","ts":"2026-02-25T02:06:09.25005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887689824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:06:09.442461Z","caller":"traceutil/trace.go:171","msg":"trace[576882768] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"904.396526ms","start":"2026-02-25T02:06:08.538029Z","end":"2026-02-25T02:06:09.442425Z","steps":["trace[576882768] 'process raft request' (duration: 870.289529ms)","trace[576882768] 'compare' (duration: 33.413993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:09.443011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:08.538007Z","time spent":"904.54846ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:06:10.521816Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080987382s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:06:10.522445Z","caller":"traceutil/trace.go:171","msg":"trace[14196784] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:268; }","duration":"1.773271816s","start":"2026-02-25T02:06:08.749143Z","end":"2026-02-25T02:06:10.522415Z","steps":["trace[14196784] 'read index received' (duration: 659.114867ms)","trace[14196784] 'applied index is now lower than readState.Index' (duration: 1.114156059s)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:10.522445Z","caller":"traceutil/trace.go:171","msg":"trace[1259571458] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.950018615s","start":"2026-02-25T02:06:08.572356Z","end":"2026-02-25T02:06:10.522375Z","steps":["trace[1259571458] 'process raft request' (duration: 1.949739199s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:10.522567Z","caller":"traceutil/trace.go:171","msg":"trace[775257789] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"1.458745318s","start":"2026-02-25T02:06:09.063808Z","end":"2026-02-25T02:06:10.522554Z","steps":["trace[775257789] 'process raft request' (duration: 1.458452332s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.522673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.063788Z","time spent":"1.458812369s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:06:10.52269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:08.572328Z","time spent":"1.950258799s","remote":"127.0.0.1:47526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:10.522932Z","caller":"traceutil/trace.go:171","msg":"trace[1332786460] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"1.456614213s","start":"2026-02-25T02:06:09.066309Z","end":"2026-02-25T02:06:10.522923Z","steps":["trace[1332786460] 'process raft request' (duration: 1.456040849s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.522983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.066296Z","time spent":"1.456665844s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:10.523184Z","caller":"traceutil/trace.go:171","msg":"trace[1773032577] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"1.459206568s","start":"2026-02-25T02:06:09.063949Z","end":"2026-02-25T02:06:10.523156Z","steps":["trace[1773032577] 'process raft request' (duration: 1.458353559s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.523267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.063918Z","time spent":"1.459318961s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:06:10.523179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774033063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:06:10.523387Z","caller":"traceutil/trace.go:171","msg":"trace[1218299133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:261; }","duration":"1.774321069s","start":"2026-02-25T02:06:08.749052Z","end":"2026-02-25T02:06:10.523373Z","steps":["trace[1218299133] 'agreement among raft nodes before linearized reading' (duration: 1.774037753s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.523423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:08.748269Z","time spent":"1.775145556s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:06:10.523007Z","caller":"traceutil/trace.go:171","msg":"trace[1334529013] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"1.457759537s","start":"2026-02-25T02:06:09.064939Z","end":"2026-02-25T02:06:10.522698Z","steps":["trace[1334529013] 'process raft request' (duration: 1.457389349s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.523805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.064915Z","time spent":"1.458833091s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:10.686931Z","caller":"traceutil/trace.go:171","msg":"trace[920290539] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"164.384666ms","start":"2026-02-25T02:06:10.522519Z","end":"2026-02-25T02:06:10.686904Z","steps":["trace[920290539] 'read index received' (duration: 78.386602ms)","trace[920290539] 'applied index is now lower than readState.Index' (duration: 85.997014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:10.687128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623103202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-25T02:06:10.687173Z","caller":"traceutil/trace.go:171","msg":"trace[2052661075] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:262; }","duration":"1.623255846s","start":"2026-02-25T02:06:09.063905Z","end":"2026-02-25T02:06:10.687161Z","steps":["trace[2052661075] 'agreement among raft nodes before linearized reading' (duration: 1.623125293s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.687209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.063794Z","time spent":"1.623407919s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5514,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-25T02:06:10.687213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.874357ms","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-25T02:06:10.687336Z","caller":"traceutil/trace.go:171","msg":"trace[1877498112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:262; }","duration":"709.108981ms","start":"2026-02-25T02:06:09.978202Z","end":"2026-02-25T02:06:10.687311Z","steps":["trace[1877498112] 'agreement among raft nodes before linearized reading' (duration: 708.779534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.687451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.978166Z","time spent":"709.271045ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:06:10.687565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.807092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"warn","ts":"2026-02-25T02:06:10.687637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.164307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:06:10.68765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690713034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:10.687623Z","caller":"traceutil/trace.go:171","msg":"trace[1299747273] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.239273637s","start":"2026-02-25T02:06:09.448252Z","end":"2026-02-25T02:06:10.687526Z","steps":["trace[1299747273] 'process raft request' (duration: 1.152622179s)","trace[1299747273] 'compare' (duration: 85.866581ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:10.687687Z","caller":"traceutil/trace.go:171","msg":"trace[1232799030] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:263; }","duration":"162.942124ms","start":"2026-02-25T02:06:10.524704Z","end":"2026-02-25T02:06:10.687646Z","steps":["trace[1232799030] 'agreement among raft nodes before linearized reading' (duration: 162.772081ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:10.687716Z","caller":"traceutil/trace.go:171","msg":"trace[1027384314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:263; }","duration":"1.690812726s","start":"2026-02-25T02:06:08.996883Z","end":"2026-02-25T02:06:10.687695Z","steps":["trace[1027384314] 'agreement among raft nodes before linearized reading' (duration: 1.690675883s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:10.687801Z","caller":"traceutil/trace.go:171","msg":"trace[811327227] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"158.710994ms","start":"2026-02-25T02:06:10.529079Z","end":"2026-02-25T02:06:10.68779Z","steps":["trace[811327227] 'process raft request' (duration: 158.191893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.687812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.448216Z","time spent":"1.239510282s","remote":"127.0.0.1:47676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:06:10.687817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:08.996862Z","time spent":"1.690939838s","remote":"127.0.0.1:47296","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-25T02:06:10.687872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.993956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5865"} {"level":"info","ts":"2026-02-25T02:06:10.687913Z","caller":"traceutil/trace.go:171","msg":"trace[283435606] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:263; }","duration":"163.080257ms","start":"2026-02-25T02:06:10.524822Z","end":"2026-02-25T02:06:10.687903Z","steps":["trace[283435606] 'agreement among raft nodes before linearized reading' (duration: 162.997306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.687956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.092637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-02-25T02:06:10.68768Z","caller":"traceutil/trace.go:171","msg":"trace[1106639433] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:263; }","duration":"161.228028ms","start":"2026-02-25T02:06:10.526439Z","end":"2026-02-25T02:06:10.687667Z","steps":["trace[1106639433] 'agreement among raft nodes before linearized reading' (duration: 161.134066ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:10.688005Z","caller":"traceutil/trace.go:171","msg":"trace[841366544] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:263; }","duration":"163.145588ms","start":"2026-02-25T02:06:10.524844Z","end":"2026-02-25T02:06:10.687989Z","steps":["trace[841366544] 'agreement among raft nodes before linearized reading' (duration: 163.051056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.688072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.029026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-25T02:06:10.6881Z","caller":"traceutil/trace.go:171","msg":"trace[1453305330] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:263; }","duration":"163.101567ms","start":"2026-02-25T02:06:10.52499Z","end":"2026-02-25T02:06:10.688092Z","steps":["trace[1453305330] 'agreement among raft nodes before linearized reading' (duration: 163.008385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.688266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622153803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:10.688333Z","caller":"traceutil/trace.go:171","msg":"trace[1954084100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:262; }","duration":"1.622293206s","start":"2026-02-25T02:06:09.066027Z","end":"2026-02-25T02:06:10.68832Z","steps":["trace[1954084100] 'agreement among raft nodes before linearized reading' (duration: 1.621001458s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.688363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.066014Z","time spent":"1.622340608s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:06:10.687334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465397259s","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-25T02:06:10.688861Z","caller":"traceutil/trace.go:171","msg":"trace[1416675068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:262; }","duration":"1.467003604s","start":"2026-02-25T02:06:09.221834Z","end":"2026-02-25T02:06:10.688837Z","steps":["trace[1416675068] 'agreement among raft nodes before linearized reading' (duration: 1.465365019s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:10.688922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:09.221809Z","time spent":"1.467093426s","remote":"127.0.0.1:47598","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-25T02:06:18.868126Z","caller":"traceutil/trace.go:171","msg":"trace[789508914] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"131.28999ms","start":"2026-02-25T02:06:18.736812Z","end":"2026-02-25T02:06:18.868102Z","steps":["trace[789508914] 'process raft request' (duration: 131.085485ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:19.017415Z","caller":"traceutil/trace.go:171","msg":"trace[1753301032] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"279.537881ms","start":"2026-02-25T02:06:18.737848Z","end":"2026-02-25T02:06:19.017386Z","steps":["trace[1753301032] 'process raft request' (duration: 202.226282ms)","trace[1753301032] 'compare' (duration: 77.079004ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:19.01756Z","caller":"traceutil/trace.go:171","msg":"trace[1698275774] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"278.811846ms","start":"2026-02-25T02:06:18.738721Z","end":"2026-02-25T02:06:19.017533Z","steps":["trace[1698275774] 'process raft request' (duration: 278.594892ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:19.017534Z","caller":"traceutil/trace.go:171","msg":"trace[1994494479] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"279.587162ms","start":"2026-02-25T02:06:18.7379Z","end":"2026-02-25T02:06:19.017487Z","steps":["trace[1994494479] 'read index received' (duration: 130.017462ms)","trace[1994494479] 'applied index is now lower than readState.Index' (duration: 149.56642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:19.017775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.828867ms","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-25T02:06:19.017876Z","caller":"traceutil/trace.go:171","msg":"trace[658380490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"279.980771ms","start":"2026-02-25T02:06:18.737875Z","end":"2026-02-25T02:06:19.017856Z","steps":["trace[658380490] 'agreement among raft nodes before linearized reading' (duration: 279.763446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:35.003031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.812009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329924"} {"level":"info","ts":"2026-02-25T02:06:35.003136Z","caller":"traceutil/trace.go:171","msg":"trace[1162822798] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:465; }","duration":"386.963412ms","start":"2026-02-25T02:06:34.616136Z","end":"2026-02-25T02:06:35.0031Z","steps":["trace[1162822798] 'range keys from in-memory index tree' (duration: 386.334189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:35.003181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:34.616112Z","time spent":"387.057925ms","remote":"127.0.0.1:47436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329947,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-25T02:06:35.003202Z","caller":"traceutil/trace.go:171","msg":"trace[1536720284] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"111.11697ms","start":"2026-02-25T02:06:34.892051Z","end":"2026-02-25T02:06:35.003168Z","steps":["trace[1536720284] 'process raft request' (duration: 75.614223ms)","trace[1536720284] 'compare' (duration: 34.675419ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:35.199821Z","caller":"traceutil/trace.go:171","msg":"trace[1880391118] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"104.020488ms","start":"2026-02-25T02:06:35.095741Z","end":"2026-02-25T02:06:35.199762Z","steps":["trace[1880391118] 'read index received' (duration: 100.243468ms)","trace[1880391118] 'applied index is now lower than readState.Index' (duration: 3.77613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:35.200093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321455ms","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-25T02:06:35.20024Z","caller":"traceutil/trace.go:171","msg":"trace[1358436535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"104.4832ms","start":"2026-02-25T02:06:35.095692Z","end":"2026-02-25T02:06:35.200175Z","steps":["trace[1358436535] 'agreement among raft nodes before linearized reading' (duration: 104.263354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:35.200865Z","caller":"traceutil/trace.go:171","msg":"trace[539211010] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"192.457275ms","start":"2026-02-25T02:06:35.007955Z","end":"2026-02-25T02:06:35.200413Z","steps":["trace[539211010] 'process raft request' (duration: 187.966788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:37.505231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.978194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:06:37.50551Z","caller":"traceutil/trace.go:171","msg":"trace[120692222] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"253.293241ms","start":"2026-02-25T02:06:37.252166Z","end":"2026-02-25T02:06:37.505459Z","steps":["trace[120692222] 'process raft request' (duration: 98.48488ms)","trace[120692222] 'compare' (duration: 153.79556ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:37.505542Z","caller":"traceutil/trace.go:171","msg":"trace[1131180298] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"253.168979ms","start":"2026-02-25T02:06:37.252352Z","end":"2026-02-25T02:06:37.505521Z","steps":["trace[1131180298] 'process raft request' (duration: 253.117957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:37.50568Z","caller":"traceutil/trace.go:171","msg":"trace[89055516] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"253.449095ms","start":"2026-02-25T02:06:37.252192Z","end":"2026-02-25T02:06:37.505641Z","steps":["trace[89055516] 'process raft request' (duration: 253.176138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:43.801665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.529101ms","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-25T02:06:43.801694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.46179ms","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-25T02:06:43.801809Z","caller":"traceutil/trace.go:171","msg":"trace[1826049759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"272.732726ms","start":"2026-02-25T02:06:43.52904Z","end":"2026-02-25T02:06:43.801773Z","steps":["trace[1826049759] 'range keys from in-memory index tree' (duration: 272.367138ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:43.801808Z","caller":"traceutil/trace.go:171","msg":"trace[34357339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"272.635084ms","start":"2026-02-25T02:06:43.529152Z","end":"2026-02-25T02:06:43.801787Z","steps":["trace[34357339] 'range keys from in-memory index tree' (duration: 272.276016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:43.801799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.709667ms","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-25T02:06:43.801888Z","caller":"traceutil/trace.go:171","msg":"trace[1402835401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"530.833159ms","start":"2026-02-25T02:06:43.271031Z","end":"2026-02-25T02:06:43.801864Z","steps":["trace[1402835401] 'range keys from in-memory index tree' (duration: 530.530443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:43.80194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:43.271011Z","time spent":"530.915061ms","remote":"127.0.0.1:47598","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-25T02:06:43.801797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.679654ms","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-25T02:06:43.802024Z","caller":"traceutil/trace.go:171","msg":"trace[530813035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"272.160304ms","start":"2026-02-25T02:06:43.529852Z","end":"2026-02-25T02:06:43.802012Z","steps":["trace[530813035] 'range keys from in-memory index tree' (duration: 271.5245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:44.183936Z","caller":"traceutil/trace.go:171","msg":"trace[290056389] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"376.847057ms","start":"2026-02-25T02:06:43.807059Z","end":"2026-02-25T02:06:44.183906Z","steps":["trace[290056389] 'process raft request' (duration: 289.445823ms)","trace[290056389] 'compare' (duration: 87.132258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:44.184109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:43.807041Z","time spent":"376.976169ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:06:44.184289Z","caller":"traceutil/trace.go:171","msg":"trace[2038504990] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"376.248335ms","start":"2026-02-25T02:06:43.808006Z","end":"2026-02-25T02:06:44.184254Z","steps":["trace[2038504990] 'process raft request' (duration: 375.835355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:44.184504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:43.807992Z","time spent":"376.404918ms","remote":"127.0.0.1:47598","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-25T02:06:44.184575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.045558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:44.184596Z","caller":"traceutil/trace.go:171","msg":"trace[1641034958] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"376.53886ms","start":"2026-02-25T02:06:43.808027Z","end":"2026-02-25T02:06:44.184566Z","steps":["trace[1641034958] 'process raft request' (duration: 376.117972ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:44.184633Z","caller":"traceutil/trace.go:171","msg":"trace[929873490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"186.141171ms","start":"2026-02-25T02:06:43.998478Z","end":"2026-02-25T02:06:44.184619Z","steps":["trace[929873490] 'agreement among raft nodes before linearized reading' (duration: 186.060149ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:44.18451Z","caller":"traceutil/trace.go:171","msg":"trace[975243743] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:513; }","duration":"185.970447ms","start":"2026-02-25T02:06:43.998525Z","end":"2026-02-25T02:06:44.184495Z","steps":["trace[975243743] 'read index received' (duration: 97.890008ms)","trace[975243743] 'applied index is now lower than readState.Index' (duration: 88.079039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:44.184736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:43.808017Z","time spent":"376.647922ms","remote":"127.0.0.1:47598","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-25T02:06:46.386241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887690337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:06:46.702136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.325110318s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:06:46.702403Z","caller":"traceutil/trace.go:171","msg":"trace[260935243] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"816.617224ms","start":"2026-02-25T02:06:45.885768Z","end":"2026-02-25T02:06:46.702386Z","steps":["trace[260935243] 'read index received' (duration: 816.484131ms)","trace[260935243] 'applied index is now lower than readState.Index' (duration: 132.243µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:46.702455Z","caller":"traceutil/trace.go:171","msg":"trace[1063765902] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.325487986s","start":"2026-02-25T02:06:45.376938Z","end":"2026-02-25T02:06:46.702426Z","steps":["trace[1063765902] 'process raft request' (duration: 1.325343643s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.702559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.646848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:46.702579Z","caller":"traceutil/trace.go:171","msg":"trace[1768350205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"705.72122ms","start":"2026-02-25T02:06:45.996852Z","end":"2026-02-25T02:06:46.702573Z","steps":["trace[1768350205] 'agreement among raft nodes before linearized reading' (duration: 705.639117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.702598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:45.996827Z","time spent":"705.766731ms","remote":"127.0.0.1:47296","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-25T02:06:46.702586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:45.37692Z","time spent":"1.325576548s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:06:46.702689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.908331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:06:46.702764Z","caller":"traceutil/trace.go:171","msg":"trace[1306023156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"817.035333ms","start":"2026-02-25T02:06:45.885708Z","end":"2026-02-25T02:06:46.702744Z","steps":["trace[1306023156] 'agreement among raft nodes before linearized reading' (duration: 816.761177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.70277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.632444ms","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-25T02:06:46.702837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.207896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:06:46.702867Z","caller":"traceutil/trace.go:171","msg":"trace[1585350969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"514.257468ms","start":"2026-02-25T02:06:46.188593Z","end":"2026-02-25T02:06:46.702851Z","steps":["trace[1585350969] 'agreement among raft nodes before linearized reading' (duration: 514.178346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.702884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:46.18858Z","time spent":"514.299308ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:06:46.702871Z","caller":"traceutil/trace.go:171","msg":"trace[1472561173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"513.769077ms","start":"2026-02-25T02:06:46.189076Z","end":"2026-02-25T02:06:46.702845Z","steps":["trace[1472561173] 'agreement among raft nodes before linearized reading' (duration: 513.463881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.702921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:46.189062Z","time spent":"513.849689ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:06:46.703011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.694247ms","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-25T02:06:46.703049Z","caller":"traceutil/trace.go:171","msg":"trace[1941516240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"514.758587ms","start":"2026-02-25T02:06:46.188279Z","end":"2026-02-25T02:06:46.703038Z","steps":["trace[1941516240] 'agreement among raft nodes before linearized reading' (duration: 514.625845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.703078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:46.188262Z","time spent":"514.809999ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:06:46.703107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.255017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-25T02:06:46.703137Z","caller":"traceutil/trace.go:171","msg":"trace[293398334] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:496; }","duration":"461.303467ms","start":"2026-02-25T02:06:46.241824Z","end":"2026-02-25T02:06:46.703127Z","steps":["trace[293398334] 'agreement among raft nodes before linearized reading' (duration: 461.216266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:46.703163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:46.241812Z","time spent":"461.344559ms","remote":"127.0.0.1:47348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-02-25T02:06:46.703749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:45.885685Z","time spent":"817.112835ms","remote":"127.0.0.1:47598","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-25T02:06:46.880151Z","caller":"traceutil/trace.go:171","msg":"trace[372586514] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"173.104582ms","start":"2026-02-25T02:06:46.707024Z","end":"2026-02-25T02:06:46.880129Z","steps":["trace[372586514] 'process raft request' (duration: 112.498249ms)","trace[372586514] 'compare' (duration: 60.4407ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:46.880588Z","caller":"traceutil/trace.go:171","msg":"trace[1819470422] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"173.101532ms","start":"2026-02-25T02:06:46.707457Z","end":"2026-02-25T02:06:46.880559Z","steps":["trace[1819470422] 'process raft request' (duration: 172.863147ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:46.880824Z","caller":"traceutil/trace.go:171","msg":"trace[380425113] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"172.212922ms","start":"2026-02-25T02:06:46.708587Z","end":"2026-02-25T02:06:46.8808Z","steps":["trace[380425113] 'process raft request' (duration: 171.838574ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:46.880496Z","caller":"traceutil/trace.go:171","msg":"trace[1487729682] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"173.313736ms","start":"2026-02-25T02:06:46.707147Z","end":"2026-02-25T02:06:46.880461Z","steps":["trace[1487729682] 'process raft request' (duration: 172.940828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.10069Z","caller":"traceutil/trace.go:171","msg":"trace[1431762821] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"678.774686ms","start":"2026-02-25T02:06:49.421883Z","end":"2026-02-25T02:06:50.100657Z","steps":["trace[1431762821] 'process raft request' (duration: 678.589331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:50.100856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:49.421849Z","time spent":"678.934669ms","remote":"127.0.0.1:47366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:50.106504Z","caller":"traceutil/trace.go:171","msg":"trace[1277882308] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"524.183329ms","start":"2026-02-25T02:06:49.582267Z","end":"2026-02-25T02:06:50.10645Z","steps":["trace[1277882308] 'process raft request' (duration: 523.894962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.106736Z","caller":"traceutil/trace.go:171","msg":"trace[7701571] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"300.620851ms","start":"2026-02-25T02:06:49.80604Z","end":"2026-02-25T02:06:50.106661Z","steps":["trace[7701571] 'process raft request' (duration: 300.373016ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.106866Z","caller":"traceutil/trace.go:171","msg":"trace[4346725] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"100.746179ms","start":"2026-02-25T02:06:50.006094Z","end":"2026-02-25T02:06:50.10684Z","steps":["trace[4346725] 'process raft request' (duration: 100.373791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:50.106903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:49.582244Z","time spent":"524.464455ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:50.106988Z","caller":"traceutil/trace.go:171","msg":"trace[841331696] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"503.364815ms","start":"2026-02-25T02:06:49.603613Z","end":"2026-02-25T02:06:50.106978Z","steps":["trace[841331696] 'process raft request' (duration: 502.730911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:50.107044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:49.603575Z","time spent":"503.449086ms","remote":"127.0.0.1:47366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:06:50.106549Z","caller":"traceutil/trace.go:171","msg":"trace[771776457] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:556; }","duration":"108.977734ms","start":"2026-02-25T02:06:49.997539Z","end":"2026-02-25T02:06:50.106517Z","steps":["trace[771776457] 'read index received' (duration: 102.901243ms)","trace[771776457] 'applied index is now lower than readState.Index' (duration: 6.071941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:50.106905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:49.80601Z","time spent":"300.826476ms","remote":"127.0.0.1:47366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:06:50.108077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.521486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:50.108147Z","caller":"traceutil/trace.go:171","msg":"trace[500234779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"110.634559ms","start":"2026-02-25T02:06:49.997497Z","end":"2026-02-25T02:06:50.108131Z","steps":["trace[500234779] 'agreement among raft nodes before linearized reading' (duration: 109.658068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:54.807557Z","caller":"traceutil/trace.go:171","msg":"trace[173243617] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"169.599407ms","start":"2026-02-25T02:06:54.637928Z","end":"2026-02-25T02:06:54.807527Z","steps":["trace[173243617] 'process raft request' (duration: 169.403383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.151182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.902994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:06:55.151387Z","caller":"traceutil/trace.go:171","msg":"trace[2079630085] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"154.009743ms","start":"2026-02-25T02:06:54.997359Z","end":"2026-02-25T02:06:55.151368Z","steps":["trace[2079630085] 'read index received' (duration: 82.942µs)","trace[2079630085] 'applied index is now lower than readState.Index' (duration: 153.925551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:55.151521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.203499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:55.15163Z","caller":"traceutil/trace.go:171","msg":"trace[847522307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"154.395743ms","start":"2026-02-25T02:06:54.997222Z","end":"2026-02-25T02:06:55.151617Z","steps":["trace[847522307] 'agreement among raft nodes before linearized reading' (duration: 154.263171ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:55.151485Z","caller":"traceutil/trace.go:171","msg":"trace[1735606012] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"207.966396ms","start":"2026-02-25T02:06:54.943475Z","end":"2026-02-25T02:06:55.151441Z","steps":["trace[1735606012] 'process raft request' (duration: 21.694203ms)","trace[1735606012] 'compare' (duration: 185.680949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:55.213446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.986572ms","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-25T02:06:55.213556Z","caller":"traceutil/trace.go:171","msg":"trace[1025248563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:605; }","duration":"175.148145ms","start":"2026-02-25T02:06:55.038381Z","end":"2026-02-25T02:06:55.21353Z","steps":["trace[1025248563] 'agreement among raft nodes before linearized reading' (duration: 174.849629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.264477Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175854888s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:07:00.277626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188847694s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:00.277888Z","caller":"traceutil/trace.go:171","msg":"trace[1073125453] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"1.280366006s","start":"2026-02-25T02:06:58.997465Z","end":"2026-02-25T02:07:00.277831Z","steps":["trace[1073125453] 'read index received' (duration: 91.223166ms)","trace[1073125453] 'applied index is now lower than readState.Index' (duration: 1.18914149s)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:00.277992Z","caller":"traceutil/trace.go:171","msg":"trace[1015006272] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.282439541s","start":"2026-02-25T02:06:58.99554Z","end":"2026-02-25T02:07:00.27798Z","steps":["trace[1015006272] 'process raft request' (duration: 1.282211965s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.278076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:58.995493Z","time spent":"1.282535833s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:00.278115Z","caller":"traceutil/trace.go:171","msg":"trace[553239449] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.282634045s","start":"2026-02-25T02:06:58.995448Z","end":"2026-02-25T02:07:00.278082Z","steps":["trace[553239449] 'process raft request' (duration: 93.203338ms)","trace[553239449] 'compare' (duration: 1.188685811s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:00.278241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280767335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:00.278291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:58.995429Z","time spent":"1.282746057s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:00.27833Z","caller":"traceutil/trace.go:171","msg":"trace[1297442424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"1.280846998s","start":"2026-02-25T02:06:58.997442Z","end":"2026-02-25T02:07:00.278289Z","steps":["trace[1297442424] 'agreement among raft nodes before linearized reading' (duration: 1.280736375s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.278398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:58.997429Z","time spent":"1.2809567s","remote":"127.0.0.1:47296","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-25T02:07:00.336444Z","caller":"traceutil/trace.go:171","msg":"trace[1782270691] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.131617065s","start":"2026-02-25T02:06:59.204796Z","end":"2026-02-25T02:07:00.336413Z","steps":["trace[1782270691] 'process raft request' (duration: 1.1314164s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.336611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172178479s","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-25T02:07:00.33664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.204769Z","time spent":"1.131755797s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:00.336889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050198718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-pwhk5\" ","response":"range_response_count:1 size:4731"} {"level":"info","ts":"2026-02-25T02:07:00.337114Z","caller":"traceutil/trace.go:171","msg":"trace[1932767549] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-pwhk5; range_end:; response_count:1; response_revision:645; }","duration":"1.0502879s","start":"2026-02-25T02:06:59.28663Z","end":"2026-02-25T02:07:00.336918Z","steps":["trace[1932767549] 'agreement among raft nodes before linearized reading' (duration: 1.050125536s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.33715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.286607Z","time spent":"1.050536355s","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4754,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-pwhk5\" "} {"level":"info","ts":"2026-02-25T02:07:00.337151Z","caller":"traceutil/trace.go:171","msg":"trace[1281456218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.172255761s","start":"2026-02-25T02:06:59.164407Z","end":"2026-02-25T02:07:00.336663Z","steps":["trace[1281456218] 'agreement among raft nodes before linearized reading' (duration: 1.172089417s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.33731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.164356Z","time spent":"1.172941866s","remote":"127.0.0.1:47598","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-25T02:07:00.338066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078549272s","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-25T02:07:00.338182Z","caller":"traceutil/trace.go:171","msg":"trace[2139073402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:645; }","duration":"1.078728856s","start":"2026-02-25T02:06:59.259429Z","end":"2026-02-25T02:07:00.338158Z","steps":["trace[2139073402] 'agreement among raft nodes before linearized reading' (duration: 1.078332308s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.33825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.259409Z","time spent":"1.078815318s","remote":"127.0.0.1:47598","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-25T02:07:00.513041Z","caller":"traceutil/trace.go:171","msg":"trace[1213940753] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"171.119509ms","start":"2026-02-25T02:07:00.3419Z","end":"2026-02-25T02:07:00.51302Z","steps":["trace[1213940753] 'process raft request' (duration: 170.932655ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.513242Z","caller":"traceutil/trace.go:171","msg":"trace[341948341] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:670; }","duration":"165.803586ms","start":"2026-02-25T02:07:00.347399Z","end":"2026-02-25T02:07:00.513203Z","steps":["trace[341948341] 'read index received' (duration: 88.536079ms)","trace[341948341] 'applied index is now lower than readState.Index' (duration: 77.265187ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:00.513311Z","caller":"traceutil/trace.go:171","msg":"trace[1410340715] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"168.926333ms","start":"2026-02-25T02:07:00.344349Z","end":"2026-02-25T02:07:00.513276Z","steps":["trace[1410340715] 'process raft request' (duration: 168.616926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.513468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.035611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:00.513575Z","caller":"traceutil/trace.go:171","msg":"trace[1524304044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"166.170884ms","start":"2026-02-25T02:07:00.347375Z","end":"2026-02-25T02:07:00.513546Z","steps":["trace[1524304044] 'agreement among raft nodes before linearized reading' (duration: 166.00125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.690942Z","caller":"traceutil/trace.go:171","msg":"trace[64323398] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"171.817723ms","start":"2026-02-25T02:07:00.519102Z","end":"2026-02-25T02:07:00.690919Z","steps":["trace[64323398] 'process raft request' (duration: 171.585459ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.691134Z","caller":"traceutil/trace.go:171","msg":"trace[1795191306] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"165.597142ms","start":"2026-02-25T02:07:00.52553Z","end":"2026-02-25T02:07:00.691127Z","steps":["trace[1795191306] 'process raft request' (duration: 165.325516ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.899353Z","caller":"traceutil/trace.go:171","msg":"trace[1660184781] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"131.227749ms","start":"2026-02-25T02:07:00.768104Z","end":"2026-02-25T02:07:00.899331Z","steps":["trace[1660184781] 'process raft request' (duration: 131.074126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.899695Z","caller":"traceutil/trace.go:171","msg":"trace[953123470] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"131.528795ms","start":"2026-02-25T02:07:00.768148Z","end":"2026-02-25T02:07:00.899676Z","steps":["trace[953123470] 'read index received' (duration: 31.401781ms)","trace[953123470] 'applied index is now lower than readState.Index' (duration: 100.126304ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:00.899741Z","caller":"traceutil/trace.go:171","msg":"trace[918242982] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"129.84977ms","start":"2026-02-25T02:07:00.769882Z","end":"2026-02-25T02:07:00.899732Z","steps":["trace[918242982] 'process raft request' (duration: 129.592544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:00.899733Z","caller":"traceutil/trace.go:171","msg":"trace[1321134246] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"130.657766ms","start":"2026-02-25T02:07:00.769013Z","end":"2026-02-25T02:07:00.899671Z","steps":["trace[1321134246] 'process raft request' (duration: 130.273318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.899866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.70025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-42g8f\" ","response":"range_response_count:1 size:5015"} {"level":"info","ts":"2026-02-25T02:07:00.899895Z","caller":"traceutil/trace.go:171","msg":"trace[535020614] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-42g8f; range_end:; response_count:1; response_revision:659; }","duration":"131.772982ms","start":"2026-02-25T02:07:00.768113Z","end":"2026-02-25T02:07:00.899886Z","steps":["trace[535020614] 'agreement among raft nodes before linearized reading' (duration: 131.632088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:06.841847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887690696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:07:06.952548Z","caller":"traceutil/trace.go:171","msg":"trace[1388071856] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"610.879319ms","start":"2026-02-25T02:07:06.341629Z","end":"2026-02-25T02:07:06.952509Z","steps":["trace[1388071856] 'read index received' (duration: 610.687165ms)","trace[1388071856] 'applied index is now lower than readState.Index' (duration: 190.874µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:06.952615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.245799Z","time spent":"706.791314ms","remote":"127.0.0.1:47348","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:07:06.952907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.251066ms","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-25T02:07:06.952991Z","caller":"traceutil/trace.go:171","msg":"trace[1171035969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"611.366198ms","start":"2026-02-25T02:07:06.341585Z","end":"2026-02-25T02:07:06.952951Z","steps":["trace[1171035969] 'agreement among raft nodes before linearized reading' (duration: 611.074992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:06.952978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.259976ms","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-25T02:07:06.953037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.341566Z","time spent":"611.460311ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:07:06.953021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.873242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:07:06.953062Z","caller":"traceutil/trace.go:171","msg":"trace[654713899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"611.361958ms","start":"2026-02-25T02:07:06.34168Z","end":"2026-02-25T02:07:06.953042Z","steps":["trace[654713899] 'agreement among raft nodes before linearized reading' (duration: 611.045981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:06.953098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.357524ms","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-25T02:07:06.953115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.341664Z","time spent":"611.44286ms","remote":"127.0.0.1:47598","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-25T02:07:06.953121Z","caller":"traceutil/trace.go:171","msg":"trace[27083181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"395.033775ms","start":"2026-02-25T02:07:06.558065Z","end":"2026-02-25T02:07:06.953099Z","steps":["trace[27083181] 'agreement among raft nodes before linearized reading' (duration: 394.689118ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:06.953165Z","caller":"traceutil/trace.go:171","msg":"trace[1644842995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"488.505698ms","start":"2026-02-25T02:07:06.464636Z","end":"2026-02-25T02:07:06.953141Z","steps":["trace[1644842995] 'agreement among raft nodes before linearized reading' (duration: 488.222072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:06.953184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.558043Z","time spent":"395.130977ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:07:06.953211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.464605Z","time spent":"488.599219ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:07:07.595707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887690700,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:08.096258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887690700,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:08.295211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.165803083s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:07:08.576596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.035544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:08.576724Z","caller":"traceutil/trace.go:171","msg":"trace[1550585619] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.621344738s","start":"2026-02-25T02:07:06.955359Z","end":"2026-02-25T02:07:08.576704Z","steps":["trace[1550585619] 'process raft request' (duration: 1.340085849s)","trace[1550585619] 'compare' (duration: 280.414492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:08.576809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.95533Z","time spent":"1.621449091s","remote":"127.0.0.1:47348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:08.643118Z","caller":"traceutil/trace.go:171","msg":"trace[1850451911] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.684504276s","start":"2026-02-25T02:07:06.958574Z","end":"2026-02-25T02:07:08.643078Z","steps":["trace[1850451911] 'process raft request' (duration: 1.684394474s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.643352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.958549Z","time spent":"1.68468074s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:08.64342Z","caller":"traceutil/trace.go:171","msg":"trace[1622687417] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.684791922s","start":"2026-02-25T02:07:06.958604Z","end":"2026-02-25T02:07:08.643396Z","steps":["trace[1622687417] 'process raft request' (duration: 1.684425574s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.643637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.958576Z","time spent":"1.684921725s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:08.643757Z","caller":"traceutil/trace.go:171","msg":"trace[594429638] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.685251122s","start":"2026-02-25T02:07:06.958473Z","end":"2026-02-25T02:07:08.643724Z","steps":["trace[594429638] 'process raft request' (duration: 1.68424454s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:08.643947Z","caller":"traceutil/trace.go:171","msg":"trace[193595149] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.684639098s","start":"2026-02-25T02:07:06.959292Z","end":"2026-02-25T02:07:08.643931Z","steps":["trace[193595149] 'process raft request' (duration: 1.684017085s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.644035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.95928Z","time spent":"1.684708229s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:08.64411Z","caller":"traceutil/trace.go:171","msg":"trace[1256003851] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:713; }","duration":"1.549090758s","start":"2026-02-25T02:07:07.094999Z","end":"2026-02-25T02:07:08.644089Z","steps":["trace[1256003851] 'read index received' (duration: 1.200463103s)","trace[1256003851] 'applied index is now lower than readState.Index' (duration: 348.626515ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:08.644173Z","caller":"traceutil/trace.go:171","msg":"trace[1715947870] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.408308946s","start":"2026-02-25T02:07:07.235845Z","end":"2026-02-25T02:07:08.644154Z","steps":["trace[1715947870] 'process raft request' (duration: 1.408000429s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.644244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.235809Z","time spent":"1.408398697s","remote":"127.0.0.1:47598","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-25T02:07:08.644282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18864621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:08.644308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549339873s","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-25T02:07:08.644332Z","caller":"traceutil/trace.go:171","msg":"trace[1514847405] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:692; }","duration":"1.188694571s","start":"2026-02-25T02:07:07.455621Z","end":"2026-02-25T02:07:08.644316Z","steps":["trace[1514847405] 'agreement among raft nodes before linearized reading' (duration: 1.18861597s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:08.644347Z","caller":"traceutil/trace.go:171","msg":"trace[1228840141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"1.549443265s","start":"2026-02-25T02:07:07.094891Z","end":"2026-02-25T02:07:08.644334Z","steps":["trace[1228840141] 'agreement among raft nodes before linearized reading' (duration: 1.549293402s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.644379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.09486Z","time spent":"1.549510997s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:08.644379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.455525Z","time spent":"1.188842704s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:07:08.644492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.760095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:08.644522Z","caller":"traceutil/trace.go:171","msg":"trace[1029389493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"647.815425ms","start":"2026-02-25T02:07:07.996699Z","end":"2026-02-25T02:07:08.644514Z","steps":["trace[1029389493] 'agreement among raft nodes before linearized reading' (duration: 647.770475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.644546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.996686Z","time spent":"647.855276ms","remote":"127.0.0.1:47308","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-25T02:07:08.644582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.285204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:08.644616Z","caller":"traceutil/trace.go:171","msg":"trace[1976905635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"684.365075ms","start":"2026-02-25T02:07:07.96024Z","end":"2026-02-25T02:07:08.644605Z","steps":["trace[1976905635] 'agreement among raft nodes before linearized reading' (duration: 684.310134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:08.644647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.960211Z","time spent":"684.429657ms","remote":"127.0.0.1:47296","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-25T02:07:08.644927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:06.958463Z","time spent":"1.685390495s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:09.308403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.101114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:09.308539Z","caller":"traceutil/trace.go:171","msg":"trace[84047163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"310.270957ms","start":"2026-02-25T02:07:08.998221Z","end":"2026-02-25T02:07:09.308492Z","steps":["trace[84047163] 'range keys from in-memory index tree' (duration: 309.966461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:09.308943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:08.99819Z","time spent":"310.737657ms","remote":"127.0.0.1:47296","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-25T02:07:09.412985Z","caller":"traceutil/trace.go:171","msg":"trace[237666084] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"102.837982ms","start":"2026-02-25T02:07:09.310116Z","end":"2026-02-25T02:07:09.412954Z","steps":["trace[237666084] 'read index received' (duration: 102.645218ms)","trace[237666084] 'applied index is now lower than readState.Index' (duration: 188.914µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:09.413167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.030077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:09.413215Z","caller":"traceutil/trace.go:171","msg":"trace[318498744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"103.14282ms","start":"2026-02-25T02:07:09.310059Z","end":"2026-02-25T02:07:09.413202Z","steps":["trace[318498744] 'agreement among raft nodes before linearized reading' (duration: 103.011746ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:09.413453Z","caller":"traceutil/trace.go:171","msg":"trace[643699998] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"393.635385ms","start":"2026-02-25T02:07:09.019787Z","end":"2026-02-25T02:07:09.413422Z","steps":["trace[643699998] 'process raft request' (duration: 392.93984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:09.413611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:09.019762Z","time spent":"393.757148ms","remote":"127.0.0.1:47494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:11.25422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887690722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:07:11.282373Z","caller":"traceutil/trace.go:171","msg":"trace[484511246] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"624.850116ms","start":"2026-02-25T02:07:10.657503Z","end":"2026-02-25T02:07:11.282354Z","steps":["trace[484511246] 'process raft request' (duration: 624.715943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.282502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.657484Z","time spent":"624.945879ms","remote":"127.0.0.1:47598","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-25T02:07:11.701314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.166043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:11.701653Z","caller":"traceutil/trace.go:171","msg":"trace[271482909] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.043562626s","start":"2026-02-25T02:07:10.658045Z","end":"2026-02-25T02:07:11.701607Z","steps":["trace[271482909] 'process raft request' (duration: 1.043339851s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.701831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.658013Z","time spent":"1.04371554s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:11.701865Z","caller":"traceutil/trace.go:171","msg":"trace[2008920876] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.02029339s","start":"2026-02-25T02:07:10.681541Z","end":"2026-02-25T02:07:11.701835Z","steps":["trace[2008920876] 'process raft request' (duration: 1.019990453s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:11.701889Z","caller":"traceutil/trace.go:171","msg":"trace[1586046111] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"1.043904693s","start":"2026-02-25T02:07:10.65796Z","end":"2026-02-25T02:07:11.701864Z","steps":["trace[1586046111] 'process raft request' (duration: 727.108077ms)","trace[1586046111] 'compare' (duration: 315.975789ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:11.70191Z","caller":"traceutil/trace.go:171","msg":"trace[1966023125] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.043806081s","start":"2026-02-25T02:07:10.658077Z","end":"2026-02-25T02:07:11.701883Z","steps":["trace[1966023125] 'process raft request' (duration: 1.043378282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.702019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.681526Z","time spent":"1.020404323s","remote":"127.0.0.1:47598","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-25T02:07:11.702048Z","caller":"traceutil/trace.go:171","msg":"trace[934698516] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:721; }","duration":"948.161032ms","start":"2026-02-25T02:07:10.753877Z","end":"2026-02-25T02:07:11.702038Z","steps":["trace[934698516] 'read index received' (duration: 528.43735ms)","trace[934698516] 'applied index is now lower than readState.Index' (duration: 419.721842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:11.70205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.658067Z","time spent":"1.043901644s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:11.702048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.657934Z","time spent":"1.044027596s","remote":"127.0.0.1:47598","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-25T02:07:11.808875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.289368ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T02:07:11.808933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055029671s","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-25T02:07:11.809004Z","caller":"traceutil/trace.go:171","msg":"trace[1632134888] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"106.915189ms","start":"2026-02-25T02:07:11.702066Z","end":"2026-02-25T02:07:11.808981Z","steps":["trace[1632134888] 'read index received' (duration: 86.491µs)","trace[1632134888] 'applied index is now lower than readState.Index' (duration: 106.826758ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:11.809009Z","caller":"traceutil/trace.go:171","msg":"trace[283625263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"1.055152093s","start":"2026-02-25T02:07:10.753831Z","end":"2026-02-25T02:07:11.808983Z","steps":["trace[283625263] 'agreement among raft nodes before linearized reading' (duration: 948.303125ms)","trace[283625263] 'range keys from in-memory index tree' (duration: 106.623534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:11.809065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.753813Z","time spent":"1.055238766s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:11.809077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.383364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:11.809103Z","caller":"traceutil/trace.go:171","msg":"trace[726448697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"811.449316ms","start":"2026-02-25T02:07:10.997644Z","end":"2026-02-25T02:07:11.809093Z","steps":["trace[726448697] 'agreement among raft nodes before linearized reading' (duration: 811.395035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.80913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:10.997617Z","time spent":"811.506607ms","remote":"127.0.0.1:47308","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-25T02:07:11.809184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.390693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:07:11.809438Z","caller":"traceutil/trace.go:171","msg":"trace[2036824312] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:699; }","duration":"644.519336ms","start":"2026-02-25T02:07:11.164721Z","end":"2026-02-25T02:07:11.80924Z","steps":["trace[2036824312] 'agreement among raft nodes before linearized reading' (duration: 644.379893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.809235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.667644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:11.809502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:11.164699Z","time spent":"644.791281ms","remote":"127.0.0.1:47492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:07:11.809555Z","caller":"traceutil/trace.go:171","msg":"trace[2009136127] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:699; }","duration":"536.056022ms","start":"2026-02-25T02:07:11.273466Z","end":"2026-02-25T02:07:11.809522Z","steps":["trace[2009136127] 'agreement among raft nodes before linearized reading' (duration: 535.601102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:11.809629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:11.273448Z","time spent":"536.164335ms","remote":"127.0.0.1:47482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:07:12.161299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.551049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:12.161406Z","caller":"traceutil/trace.go:171","msg":"trace[849385170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"164.713063ms","start":"2026-02-25T02:07:11.996669Z","end":"2026-02-25T02:07:12.161382Z","steps":["trace[849385170] 'range keys from in-memory index tree' (duration: 164.468037ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:29.988721Z","caller":"traceutil/trace.go:171","msg":"trace[1856961579] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"109.26902ms","start":"2026-02-25T02:07:29.879423Z","end":"2026-02-25T02:07:29.988692Z","steps":["trace[1856961579] 'process raft request' (duration: 109.068286ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:30.044992Z","caller":"traceutil/trace.go:171","msg":"trace[17758633] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"163.19277ms","start":"2026-02-25T02:07:29.881762Z","end":"2026-02-25T02:07:30.044955Z","steps":["trace[17758633] 'process raft request' (duration: 163.131569ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:30.04551Z","caller":"traceutil/trace.go:171","msg":"trace[1562893292] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"164.431937ms","start":"2026-02-25T02:07:29.881017Z","end":"2026-02-25T02:07:30.045449Z","steps":["trace[1562893292] 'process raft request' (duration: 158.295496ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:34.224353Z","caller":"traceutil/trace.go:171","msg":"trace[1384422524] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"131.438564ms","start":"2026-02-25T02:07:34.092879Z","end":"2026-02-25T02:07:34.224318Z","steps":["trace[1384422524] 'process raft request' (duration: 116.778111ms)","trace[1384422524] 'compare' (duration: 13.767964ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:34.224493Z","caller":"traceutil/trace.go:171","msg":"trace[839252445] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:795; }","duration":"107.82798ms","start":"2026-02-25T02:07:34.116637Z","end":"2026-02-25T02:07:34.224465Z","steps":["trace[839252445] 'read index received' (duration: 93.014064ms)","trace[839252445] 'applied index is now lower than readState.Index' (duration: 14.811886ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:34.224598Z","caller":"traceutil/trace.go:171","msg":"trace[1713201758] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"129.613554ms","start":"2026-02-25T02:07:34.094973Z","end":"2026-02-25T02:07:34.224587Z","steps":["trace[1713201758] 'process raft request' (duration: 129.281497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:34.224688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.032544ms","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-25T02:07:34.224783Z","caller":"traceutil/trace.go:171","msg":"trace[2092114854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"108.127746ms","start":"2026-02-25T02:07:34.116591Z","end":"2026-02-25T02:07:34.224718Z","steps":["trace[2092114854] 'agreement among raft nodes before linearized reading' (duration: 107.970363ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:35.695867Z","caller":"traceutil/trace.go:171","msg":"trace[1349512703] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"142.194213ms","start":"2026-02-25T02:07:35.553649Z","end":"2026-02-25T02:07:35.695843Z","steps":["trace[1349512703] 'process raft request' (duration: 142.05217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.145933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.687687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-25T02:07:37.146023Z","caller":"traceutil/trace.go:171","msg":"trace[139057073] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:767; }","duration":"899.810101ms","start":"2026-02-25T02:07:36.246192Z","end":"2026-02-25T02:07:37.146002Z","steps":["trace[139057073] 'range keys from in-memory index tree' (duration: 899.471034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.14629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.246182Z","time spent":"900.096036ms","remote":"127.0.0.1:47348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-02-25T02:07:37.1464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094323878s","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-25T02:07:37.146445Z","caller":"traceutil/trace.go:171","msg":"trace[1989089589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"1.094444341s","start":"2026-02-25T02:07:36.051988Z","end":"2026-02-25T02:07:37.146432Z","steps":["trace[1989089589] 'range keys from in-memory index tree' (duration: 1.094156995s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.146482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.05197Z","time spent":"1.094498921s","remote":"127.0.0.1:47598","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-25T02:07:37.146699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.775303ms","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-25T02:07:37.146781Z","caller":"traceutil/trace.go:171","msg":"trace[1549512684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"887.899717ms","start":"2026-02-25T02:07:36.258862Z","end":"2026-02-25T02:07:37.146762Z","steps":["trace[1549512684] 'range keys from in-memory index tree' (duration: 887.588299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.146823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.258845Z","time spent":"887.968868ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:37.146853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.006061ms","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":"warn","ts":"2026-02-25T02:07:37.146842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.490613ms","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-25T02:07:37.146888Z","caller":"traceutil/trace.go:171","msg":"trace[963545443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"913.089103ms","start":"2026-02-25T02:07:36.233789Z","end":"2026-02-25T02:07:37.146878Z","steps":["trace[963545443] 'range keys from in-memory index tree' (duration: 912.840528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.1469Z","caller":"traceutil/trace.go:171","msg":"trace[1588122960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"913.596875ms","start":"2026-02-25T02:07:36.233291Z","end":"2026-02-25T02:07:37.146888Z","steps":["trace[1588122960] 'range keys from in-memory index tree' (duration: 913.314728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.146917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.233733Z","time spent":"913.177216ms","remote":"127.0.0.1:47598","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":"warn","ts":"2026-02-25T02:07:37.146935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.233261Z","time spent":"913.664577ms","remote":"127.0.0.1:47598","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":"warn","ts":"2026-02-25T02:07:37.151062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153419969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:37.15123Z","caller":"traceutil/trace.go:171","msg":"trace[1009608917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"1.153629583s","start":"2026-02-25T02:07:35.997546Z","end":"2026-02-25T02:07:37.151175Z","steps":["trace[1009608917] 'range keys from in-memory index tree' (duration: 1.149308372s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.151297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.997515Z","time spent":"1.153764106s","remote":"127.0.0.1:47296","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-25T02:07:37.356366Z","caller":"traceutil/trace.go:171","msg":"trace[1135991952] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"192.209888ms","start":"2026-02-25T02:07:37.16413Z","end":"2026-02-25T02:07:37.356339Z","steps":["trace[1135991952] 'process raft request' (duration: 192.001294ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.356378Z","caller":"traceutil/trace.go:171","msg":"trace[1349770552] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"192.624987ms","start":"2026-02-25T02:07:37.163705Z","end":"2026-02-25T02:07:37.35633Z","steps":["trace[1349770552] 'process raft request' (duration: 192.191538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.356752Z","caller":"traceutil/trace.go:171","msg":"trace[1438736005] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"191.995294ms","start":"2026-02-25T02:07:37.164735Z","end":"2026-02-25T02:07:37.35673Z","steps":["trace[1438736005] 'process raft request' (duration: 191.875711ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.356771Z","caller":"traceutil/trace.go:171","msg":"trace[748779315] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"192.098996ms","start":"2026-02-25T02:07:37.164642Z","end":"2026-02-25T02:07:37.356741Z","steps":["trace[748779315] 'process raft request' (duration: 191.647166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.37083Z","caller":"traceutil/trace.go:171","msg":"trace[2097263241] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"176.952444ms","start":"2026-02-25T02:07:37.193845Z","end":"2026-02-25T02:07:37.370798Z","steps":["trace[2097263241] 'process raft request' (duration: 176.690908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.371026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.663833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:37.370813Z","caller":"traceutil/trace.go:171","msg":"trace[96195711] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:800; }","duration":"165.445658ms","start":"2026-02-25T02:07:37.205329Z","end":"2026-02-25T02:07:37.370775Z","steps":["trace[96195711] 'read index received' (duration: 150.482339ms)","trace[96195711] 'applied index is now lower than readState.Index' (duration: 14.961799ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:37.37111Z","caller":"traceutil/trace.go:171","msg":"trace[2137901829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"165.825468ms","start":"2026-02-25T02:07:37.205262Z","end":"2026-02-25T02:07:37.371087Z","steps":["trace[2137901829] 'agreement among raft nodes before linearized reading' (duration: 165.640584ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:39.807553Z","caller":"traceutil/trace.go:171","msg":"trace[78274537] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"441.346722ms","start":"2026-02-25T02:07:39.366167Z","end":"2026-02-25T02:07:39.807514Z","steps":["trace[78274537] 'process raft request' (duration: 440.991625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:39.807973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.366142Z","time spent":"441.619798ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:40.688125Z","caller":"traceutil/trace.go:171","msg":"trace[165637982] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.30864223s","start":"2026-02-25T02:07:39.379447Z","end":"2026-02-25T02:07:40.688089Z","steps":["trace[165637982] 'process raft request' (duration: 1.308562188s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:40.688241Z","caller":"traceutil/trace.go:171","msg":"trace[1051305499] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.319586733s","start":"2026-02-25T02:07:39.36858Z","end":"2026-02-25T02:07:40.688166Z","steps":["trace[1051305499] 'process raft request' (duration: 1.319235016s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:40.688332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.379439Z","time spent":"1.308784143s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:40.688484Z","caller":"traceutil/trace.go:171","msg":"trace[931279494] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:806; }","duration":"923.469605ms","start":"2026-02-25T02:07:39.764986Z","end":"2026-02-25T02:07:40.688455Z","steps":["trace[931279494] 'read index received' (duration: 42.291482ms)","trace[931279494] 'applied index is now lower than readState.Index' (duration: 881.176953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:40.688556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.368563Z","time spent":"1.319823028s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:40.688545Z","caller":"traceutil/trace.go:171","msg":"trace[457563130] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.269542985s","start":"2026-02-25T02:07:39.418967Z","end":"2026-02-25T02:07:40.68851Z","steps":["trace[457563130] 'process raft request' (duration: 1.269350171s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:40.688741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.335514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:40.688789Z","caller":"traceutil/trace.go:171","msg":"trace[1234682314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"691.440887ms","start":"2026-02-25T02:07:39.997332Z","end":"2026-02-25T02:07:40.688773Z","steps":["trace[1234682314] 'agreement among raft nodes before linearized reading' (duration: 691.288404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:40.688829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.997311Z","time spent":"691.506428ms","remote":"127.0.0.1:47296","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-25T02:07:40.688881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.418959Z","time spent":"1.26977417s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:40.689287Z","caller":"traceutil/trace.go:171","msg":"trace[575698172] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.308877364s","start":"2026-02-25T02:07:39.379716Z","end":"2026-02-25T02:07:40.688593Z","steps":["trace[575698172] 'process raft request' (duration: 1.308337103s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:40.689425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.447906ms","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-25T02:07:40.68947Z","caller":"traceutil/trace.go:171","msg":"trace[678736161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"924.546798ms","start":"2026-02-25T02:07:39.76491Z","end":"2026-02-25T02:07:40.689457Z","steps":["trace[678736161] 'agreement among raft nodes before linearized reading' (duration: 923.69978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:40.689502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.764866Z","time spent":"924.62691ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:07:40.689679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:39.37971Z","time spent":"1.309883365s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:41.204869Z","caller":"traceutil/trace.go:171","msg":"trace[600149580] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"205.281968ms","start":"2026-02-25T02:07:40.99956Z","end":"2026-02-25T02:07:41.204842Z","steps":["trace[600149580] 'read index received' (duration: 156.760353ms)","trace[600149580] 'applied index is now lower than readState.Index' (duration: 48.520205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:41.205017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.450101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:41.205049Z","caller":"traceutil/trace.go:171","msg":"trace[789264395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"205.557844ms","start":"2026-02-25T02:07:40.999481Z","end":"2026-02-25T02:07:41.205039Z","steps":["trace[789264395] 'agreement among raft nodes before linearized reading' (duration: 205.481482ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:41.20512Z","caller":"traceutil/trace.go:171","msg":"trace[1631862898] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"509.269342ms","start":"2026-02-25T02:07:40.695822Z","end":"2026-02-25T02:07:41.205091Z","steps":["trace[1631862898] 'process raft request' (duration: 460.643115ms)","trace[1631862898] 'compare' (duration: 48.266859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:41.205312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:40.695807Z","time spent":"509.389624ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:50.896469Z","caller":"traceutil/trace.go:171","msg":"trace[564633492] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"144.444851ms","start":"2026-02-25T02:07:50.751997Z","end":"2026-02-25T02:07:50.896442Z","steps":["trace[564633492] 'read index received' (duration: 144.243797ms)","trace[564633492] 'applied index is now lower than readState.Index' (duration: 199.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:50.896845Z","caller":"traceutil/trace.go:171","msg":"trace[1963239233] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"159.644165ms","start":"2026-02-25T02:07:50.737189Z","end":"2026-02-25T02:07:50.896833Z","steps":["trace[1963239233] 'process raft request' (duration: 159.088803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:50.897204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.160486ms","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":"info","ts":"2026-02-25T02:07:50.898075Z","caller":"traceutil/trace.go:171","msg":"trace[1753428399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"146.073665ms","start":"2026-02-25T02:07:50.751971Z","end":"2026-02-25T02:07:50.898045Z","steps":["trace[1753428399] 'agreement among raft nodes before linearized reading' (duration: 144.986262ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:50.905996Z","caller":"traceutil/trace.go:171","msg":"trace[829164192] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"153.928404ms","start":"2026-02-25T02:07:50.752053Z","end":"2026-02-25T02:07:50.905981Z","steps":["trace[829164192] 'process raft request' (duration: 153.830151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:50.906097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.961483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:07:50.906133Z","caller":"traceutil/trace.go:171","msg":"trace[539430161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"154.032536ms","start":"2026-02-25T02:07:50.752092Z","end":"2026-02-25T02:07:50.906125Z","steps":["trace[539430161] 'agreement among raft nodes before linearized reading' (duration: 153.871822ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.03825Z","caller":"traceutil/trace.go:171","msg":"trace[1041733561] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"136.183134ms","start":"2026-02-25T02:07:50.902047Z","end":"2026-02-25T02:07:51.03823Z","steps":["trace[1041733561] 'process raft request' (duration: 136.038211ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.040048Z","caller":"traceutil/trace.go:171","msg":"trace[696813590] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"130.32879ms","start":"2026-02-25T02:07:50.909704Z","end":"2026-02-25T02:07:51.040033Z","steps":["trace[696813590] 'process raft request' (duration: 130.085364ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.258731Z","caller":"traceutil/trace.go:171","msg":"trace[410190774] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"133.699022ms","start":"2026-02-25T02:07:51.125Z","end":"2026-02-25T02:07:51.258699Z","steps":["trace[410190774] 'process raft request' (duration: 133.439567ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.602312Z","caller":"traceutil/trace.go:171","msg":"trace[1837174258] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"137.986113ms","start":"2026-02-25T02:07:51.464299Z","end":"2026-02-25T02:07:51.602286Z","steps":["trace[1837174258] 'process raft request' (duration: 137.937932ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.602336Z","caller":"traceutil/trace.go:171","msg":"trace[1141163948] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"138.030915ms","start":"2026-02-25T02:07:51.464284Z","end":"2026-02-25T02:07:51.602314Z","steps":["trace[1141163948] 'process raft request' (duration: 102.933606ms)","trace[1141163948] 'compare' (duration: 34.827473ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:55.054461Z","caller":"traceutil/trace.go:171","msg":"trace[903221908] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"126.245653ms","start":"2026-02-25T02:07:54.928177Z","end":"2026-02-25T02:07:55.054423Z","steps":["trace[903221908] 'process raft request' (duration: 125.743743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.750183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887691196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:59.251448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806542887691196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:59.29936Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22006196s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:07:59.299728Z","caller":"traceutil/trace.go:171","msg":"trace[206066726] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"1.050188186s","start":"2026-02-25T02:07:58.249521Z","end":"2026-02-25T02:07:59.29971Z","steps":["trace[206066726] 'read index received' (duration: 1.050009662s)","trace[206066726] 'applied index is now lower than readState.Index' (duration: 177.654µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:59.299833Z","caller":"traceutil/trace.go:171","msg":"trace[312582285] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"1.220645342s","start":"2026-02-25T02:07:58.079151Z","end":"2026-02-25T02:07:59.299796Z","steps":["trace[312582285] 'process raft request' (duration: 1.220389277s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:59.299887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.98902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:59.299915Z","caller":"traceutil/trace.go:171","msg":"trace[1484139272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"287.058942ms","start":"2026-02-25T02:07:59.012847Z","end":"2026-02-25T02:07:59.299906Z","steps":["trace[1484139272] 'agreement among raft nodes before linearized reading' (duration: 286.982711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:59.299998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.079129Z","time spent":"1.220762095s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:59.299985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050412751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:07:59.300051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.307609ms","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-25T02:07:59.300087Z","caller":"traceutil/trace.go:171","msg":"trace[535848459] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:876; }","duration":"1.050607757s","start":"2026-02-25T02:07:58.249454Z","end":"2026-02-25T02:07:59.300062Z","steps":["trace[535848459] 'agreement among raft nodes before linearized reading' (duration: 1.050372532s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:59.300144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.249415Z","time spent":"1.05071761s","remote":"127.0.0.1:47756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:07:59.300234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.714857ms","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-25T02:07:59.300258Z","caller":"traceutil/trace.go:171","msg":"trace[621840924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:876; }","duration":"151.782798ms","start":"2026-02-25T02:07:59.148469Z","end":"2026-02-25T02:07:59.300252Z","steps":["trace[621840924] 'agreement among raft nodes before linearized reading' (duration: 151.677136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:59.300445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.558345ms","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-25T02:07:59.30049Z","caller":"traceutil/trace.go:171","msg":"trace[1621396330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"168.633097ms","start":"2026-02-25T02:07:59.131839Z","end":"2026-02-25T02:07:59.300472Z","steps":["trace[1621396330] 'agreement among raft nodes before linearized reading' (duration: 168.465964ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:59.300106Z","caller":"traceutil/trace.go:171","msg":"trace[1451155936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"129.421221ms","start":"2026-02-25T02:07:59.170671Z","end":"2026-02-25T02:07:59.300092Z","steps":["trace[1451155936] 'agreement among raft nodes before linearized reading' (duration: 129.178525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.931743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073257409s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:00.93185Z","caller":"traceutil/trace.go:171","msg":"trace[737473534] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"1.624064767s","start":"2026-02-25T02:07:59.307767Z","end":"2026-02-25T02:08:00.931832Z","steps":["trace[737473534] 'process raft request' (duration: 550.631504ms)","trace[737473534] 'compare' (duration: 1.073129087s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:00.931913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:59.307737Z","time spent":"1.624147148s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:00.964377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105889185s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:00.964726Z","caller":"traceutil/trace.go:171","msg":"trace[387963003] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:915; }","duration":"1.597583012s","start":"2026-02-25T02:07:59.367119Z","end":"2026-02-25T02:08:00.964702Z","steps":["trace[387963003] 'read index received' (duration: 491.38619ms)","trace[387963003] 'applied index is now lower than readState.Index' (duration: 1.106195782s)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:00.964836Z","caller":"traceutil/trace.go:171","msg":"trace[1836765719] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.651595714s","start":"2026-02-25T02:07:59.313205Z","end":"2026-02-25T02:08:00.964801Z","steps":["trace[1836765719] 'process raft request' (duration: 1.651456731s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.964927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597792246s","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-25T02:08:00.964968Z","caller":"traceutil/trace.go:171","msg":"trace[191518627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"1.597909349s","start":"2026-02-25T02:07:59.367045Z","end":"2026-02-25T02:08:00.964954Z","steps":["trace[191518627] 'agreement among raft nodes before linearized reading' (duration: 1.597750816s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.964831Z","caller":"traceutil/trace.go:171","msg":"trace[179645503] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.656867417s","start":"2026-02-25T02:07:59.307916Z","end":"2026-02-25T02:08:00.964783Z","steps":["trace[179645503] 'process raft request' (duration: 1.656642442s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.965002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:59.367018Z","time spent":"1.59797584s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:00.965039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:59.313191Z","time spent":"1.651733717s","remote":"127.0.0.1:47598","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-25T02:08:00.965176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:59.307903Z","time spent":"1.657133442s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:00.965116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040598193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:00.965373Z","caller":"traceutil/trace.go:171","msg":"trace[2087638388] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:879; }","duration":"1.04091061s","start":"2026-02-25T02:07:59.9244Z","end":"2026-02-25T02:08:00.965311Z","steps":["trace[2087638388] 'agreement among raft nodes before linearized reading' (duration: 1.040493571s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.965865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:59.924379Z","time spent":"1.04141166s","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:08:13.635061Z","caller":"traceutil/trace.go:171","msg":"trace[573640691] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"232.886298ms","start":"2026-02-25T02:08:13.402149Z","end":"2026-02-25T02:08:13.635035Z","steps":["trace[573640691] 'process raft request' (duration: 232.729315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:17.179757Z","caller":"traceutil/trace.go:171","msg":"trace[316893482] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"116.558572ms","start":"2026-02-25T02:08:17.063182Z","end":"2026-02-25T02:08:17.17974Z","steps":["trace[316893482] 'process raft request' (duration: 116.378408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:17.701372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.008504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:17.701529Z","caller":"traceutil/trace.go:171","msg":"trace[170748014] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:986; }","duration":"393.622608ms","start":"2026-02-25T02:08:17.307893Z","end":"2026-02-25T02:08:17.701516Z","steps":["trace[170748014] 'read index received' (duration: 131.355349ms)","trace[170748014] 'applied index is now lower than readState.Index' (duration: 262.266549ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:17.701722Z","caller":"traceutil/trace.go:171","msg":"trace[1390226978] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"515.952734ms","start":"2026-02-25T02:08:17.18576Z","end":"2026-02-25T02:08:17.701713Z","steps":["trace[1390226978] 'process raft request' (duration: 253.528522ms)","trace[1390226978] 'compare' (duration: 261.83594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:17.701775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:17.185744Z","time spent":"516.007535ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:17.701918Z","caller":"traceutil/trace.go:171","msg":"trace[67405672] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"516.107078ms","start":"2026-02-25T02:08:17.185804Z","end":"2026-02-25T02:08:17.701911Z","steps":["trace[67405672] 'process raft request' (duration: 515.649068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:17.701959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:17.185787Z","time spent":"516.151748ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:17.702055Z","caller":"traceutil/trace.go:171","msg":"trace[1525964055] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"516.19505ms","start":"2026-02-25T02:08:17.185855Z","end":"2026-02-25T02:08:17.70205Z","steps":["trace[1525964055] 'process raft request' (duration: 515.631598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:17.702097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:17.185826Z","time spent":"516.249661ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:17.702197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.311953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:17.70224Z","caller":"traceutil/trace.go:171","msg":"trace[116533551] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:946; }","duration":"394.381885ms","start":"2026-02-25T02:08:17.307834Z","end":"2026-02-25T02:08:17.702216Z","steps":["trace[116533551] 'agreement among raft nodes before linearized reading' (duration: 394.334773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:17.702288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:17.30781Z","time spent":"394.466497ms","remote":"127.0.0.1:41790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:08:17.901352Z","caller":"traceutil/trace.go:171","msg":"trace[474091535] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"193.863702ms","start":"2026-02-25T02:08:17.707457Z","end":"2026-02-25T02:08:17.901321Z","steps":["trace[474091535] 'process raft request' (duration: 187.724679ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:19.6898Z","caller":"traceutil/trace.go:171","msg":"trace[1666591691] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"500.582246ms","start":"2026-02-25T02:08:19.189188Z","end":"2026-02-25T02:08:19.68977Z","steps":["trace[1666591691] 'process raft request' (duration: 500.195668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:19.68996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:19.18915Z","time spent":"500.727299ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:19.904214Z","caller":"traceutil/trace.go:171","msg":"trace[1857059525] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"181.049707ms","start":"2026-02-25T02:08:19.72313Z","end":"2026-02-25T02:08:19.90418Z","steps":["trace[1857059525] 'process raft request' (duration: 180.944864ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:19.904639Z","caller":"traceutil/trace.go:171","msg":"trace[445424042] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"182.447506ms","start":"2026-02-25T02:08:19.722133Z","end":"2026-02-25T02:08:19.90458Z","steps":["trace[445424042] 'process raft request' (duration: 166.994164ms)","trace[445424042] 'compare' (duration: 14.789097ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:20.723832Z","caller":"traceutil/trace.go:171","msg":"trace[1756106052] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"136.357137ms","start":"2026-02-25T02:08:20.58745Z","end":"2026-02-25T02:08:20.723808Z","steps":["trace[1756106052] 'process raft request' (duration: 136.191204ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:21.94889Z","caller":"traceutil/trace.go:171","msg":"trace[978137408] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"468.797382ms","start":"2026-02-25T02:08:21.480066Z","end":"2026-02-25T02:08:21.948864Z","steps":["trace[978137408] 'process raft request' (duration: 468.619458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:21.949131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.480039Z","time spent":"468.939935ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:23.061075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.660694ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:08:23.061241Z","caller":"traceutil/trace.go:171","msg":"trace[11768287] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"1.366535162s","start":"2026-02-25T02:08:21.694678Z","end":"2026-02-25T02:08:23.061213Z","steps":["trace[11768287] 'read index received' (duration: 254.027312ms)","trace[11768287] 'applied index is now lower than readState.Index' (duration: 1.11250577s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:23.061548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28768819s","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-25T02:08:23.061508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366824638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T02:08:23.061646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063864895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:23.061653Z","caller":"traceutil/trace.go:171","msg":"trace[1549053199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"1.367030253s","start":"2026-02-25T02:08:21.69459Z","end":"2026-02-25T02:08:23.06162Z","steps":["trace[1549053199] 'agreement among raft nodes before linearized reading' (duration: 1.366690446s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:23.06168Z","caller":"traceutil/trace.go:171","msg":"trace[1246206204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"1.063930747s","start":"2026-02-25T02:08:21.99774Z","end":"2026-02-25T02:08:23.061671Z","steps":["trace[1246206204] 'agreement among raft nodes before linearized reading' (duration: 1.063883986s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:23.061732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.997724Z","time spent":"1.063999898s","remote":"127.0.0.1:47296","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-25T02:08:23.061592Z","caller":"traceutil/trace.go:171","msg":"trace[1381929494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.287814143s","start":"2026-02-25T02:08:21.773767Z","end":"2026-02-25T02:08:23.061581Z","steps":["trace[1381929494] 'agreement among raft nodes before linearized reading' (duration: 1.287580778s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:23.061778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.773733Z","time spent":"1.288035327s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:23.061728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.694555Z","time spent":"1.367156165s","remote":"127.0.0.1:47598","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":"warn","ts":"2026-02-25T02:08:23.061981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124922116s","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-25T02:08:23.061982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151818453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:08:23.062046Z","caller":"traceutil/trace.go:171","msg":"trace[712129166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"1.125073559s","start":"2026-02-25T02:08:21.936956Z","end":"2026-02-25T02:08:23.062029Z","steps":["trace[712129166] 'agreement among raft nodes before linearized reading' (duration: 1.124924775s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:23.062056Z","caller":"traceutil/trace.go:171","msg":"trace[1265317586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.151938446s","start":"2026-02-25T02:08:21.910102Z","end":"2026-02-25T02:08:23.06204Z","steps":["trace[1265317586] 'agreement among raft nodes before linearized reading' (duration: 1.15164472s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:23.062111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.936926Z","time spent":"1.125150481s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:08:23.062099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.910087Z","time spent":"1.152000377s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:23.062755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152108769s","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-25T02:08:23.062831Z","caller":"traceutil/trace.go:171","msg":"trace[748614116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.152728532s","start":"2026-02-25T02:08:21.910081Z","end":"2026-02-25T02:08:23.06281Z","steps":["trace[748614116] 'agreement among raft nodes before linearized reading' (duration: 1.152072868s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:23.06288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.910052Z","time spent":"1.152811334s","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:08:23.299237Z","caller":"traceutil/trace.go:171","msg":"trace[2032943405] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"231.518288ms","start":"2026-02-25T02:08:23.067679Z","end":"2026-02-25T02:08:23.299197Z","steps":["trace[2032943405] 'process raft request' (duration: 231.206831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:23.29963Z","caller":"traceutil/trace.go:171","msg":"trace[645188378] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"231.575821ms","start":"2026-02-25T02:08:23.068042Z","end":"2026-02-25T02:08:23.299618Z","steps":["trace[645188378] 'process raft request' (duration: 231.388797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:23.299667Z","caller":"traceutil/trace.go:171","msg":"trace[1355111173] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:998; }","duration":"227.31479ms","start":"2026-02-25T02:08:23.07225Z","end":"2026-02-25T02:08:23.299565Z","steps":["trace[1355111173] 'read index received' (duration: 28.747618ms)","trace[1355111173] 'applied index is now lower than readState.Index' (duration: 198.566012ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:23.299718Z","caller":"traceutil/trace.go:171","msg":"trace[1166430063] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"230.888855ms","start":"2026-02-25T02:08:23.068804Z","end":"2026-02-25T02:08:23.299692Z","steps":["trace[1166430063] 'process raft request' (duration: 230.704932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:23.299786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.497904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:23.299823Z","caller":"traceutil/trace.go:171","msg":"trace[1693329348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"227.577085ms","start":"2026-02-25T02:08:23.072234Z","end":"2026-02-25T02:08:23.299811Z","steps":["trace[1693329348] 'agreement among raft nodes before linearized reading' (duration: 227.477693ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:23.300224Z","caller":"traceutil/trace.go:171","msg":"trace[1260146393] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"231.5662ms","start":"2026-02-25T02:08:23.068016Z","end":"2026-02-25T02:08:23.299582Z","steps":["trace[1260146393] 'process raft request' (duration: 231.11936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.103439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.773222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:24.103629Z","caller":"traceutil/trace.go:171","msg":"trace[1634034026] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"709.634383ms","start":"2026-02-25T02:08:23.393938Z","end":"2026-02-25T02:08:24.103572Z","steps":["trace[1634034026] 'read index received' (duration: 145.591485ms)","trace[1634034026] 'applied index is now lower than readState.Index' (duration: 564.041018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:24.103713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.777605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:24.103761Z","caller":"traceutil/trace.go:171","msg":"trace[538509992] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:961; }","duration":"709.862547ms","start":"2026-02-25T02:08:23.393876Z","end":"2026-02-25T02:08:24.103739Z","steps":["trace[538509992] 'agreement among raft nodes before linearized reading' (duration: 709.788056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.103807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:23.393847Z","time spent":"709.951879ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T02:08:24.103793Z","caller":"traceutil/trace.go:171","msg":"trace[755787215] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"792.172183ms","start":"2026-02-25T02:08:23.311566Z","end":"2026-02-25T02:08:24.103738Z","steps":["trace[755787215] 'process raft request' (duration: 228.100015ms)","trace[755787215] 'compare' (duration: 563.299481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:24.104076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:23.311544Z","time spent":"792.339216ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:24.489598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.208869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:24.489757Z","caller":"traceutil/trace.go:171","msg":"trace[1871720224] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"377.713906ms","start":"2026-02-25T02:08:24.112016Z","end":"2026-02-25T02:08:24.48973Z","steps":["trace[1871720224] 'process raft request' (duration: 141.18877ms)","trace[1871720224] 'compare' (duration: 235.661177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:24.489826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:24.111999Z","time spent":"377.798377ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:24.527632Z","caller":"traceutil/trace.go:171","msg":"trace[1135683000] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"393.343582ms","start":"2026-02-25T02:08:24.134233Z","end":"2026-02-25T02:08:24.527577Z","steps":["trace[1135683000] 'process raft request' (duration: 393.195858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.527887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:24.13419Z","time spent":"393.540866ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:08:24.796382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.72449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:24.796644Z","caller":"traceutil/trace.go:171","msg":"trace[1587262736] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"266.720454ms","start":"2026-02-25T02:08:24.529887Z","end":"2026-02-25T02:08:24.796607Z","steps":["trace[1587262736] 'process raft request' (duration: 64.649767ms)","trace[1587262736] 'compare' (duration: 201.429604ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:24.796745Z","caller":"traceutil/trace.go:171","msg":"trace[970683936] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1007; }","duration":"214.34171ms","start":"2026-02-25T02:08:24.582381Z","end":"2026-02-25T02:08:24.796722Z","steps":["trace[970683936] 'read index received' (duration: 12.172661ms)","trace[970683936] 'applied index is now lower than readState.Index' (duration: 202.167419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:24.796857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.462682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:24.796904Z","caller":"traceutil/trace.go:171","msg":"trace[1852747778] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:966; }","duration":"214.568475ms","start":"2026-02-25T02:08:24.58232Z","end":"2026-02-25T02:08:24.796889Z","steps":["trace[1852747778] 'agreement among raft nodes before linearized reading' (duration: 214.472113ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:24.797022Z","caller":"traceutil/trace.go:171","msg":"trace[1166608614] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"259.915668ms","start":"2026-02-25T02:08:24.537062Z","end":"2026-02-25T02:08:24.796977Z","steps":["trace[1166608614] 'process raft request' (duration: 259.562691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:24.796744Z","caller":"traceutil/trace.go:171","msg":"trace[1351247759] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"265.460018ms","start":"2026-02-25T02:08:24.531254Z","end":"2026-02-25T02:08:24.796714Z","steps":["trace[1351247759] 'process raft request' (duration: 265.294294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.856212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.043346ms","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-25T02:08:25.856305Z","caller":"traceutil/trace.go:171","msg":"trace[449212582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"551.215531ms","start":"2026-02-25T02:08:25.305068Z","end":"2026-02-25T02:08:25.856283Z","steps":["trace[449212582] 'range keys from in-memory index tree' (duration: 550.820802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.856385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.305035Z","time spent":"551.340564ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:08:25.856619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.233521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:08:25.856642Z","caller":"traceutil/trace.go:171","msg":"trace[2114475930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"551.427855ms","start":"2026-02-25T02:08:25.305206Z","end":"2026-02-25T02:08:25.856634Z","steps":["trace[2114475930] 'range keys from in-memory index tree' (duration: 549.898403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.856662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.305173Z","time spent":"551.483536ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:25.856707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.014149ms","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-25T02:08:25.856691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.474536ms","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-25T02:08:25.856769Z","caller":"traceutil/trace.go:171","msg":"trace[541175573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"737.125642ms","start":"2026-02-25T02:08:25.119628Z","end":"2026-02-25T02:08:25.856754Z","steps":["trace[541175573] 'range keys from in-memory index tree' (duration: 736.833705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.856798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.119585Z","time spent":"737.207074ms","remote":"127.0.0.1:47598","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-25T02:08:25.85679Z","caller":"traceutil/trace.go:171","msg":"trace[1937106593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"551.601878ms","start":"2026-02-25T02:08:25.305165Z","end":"2026-02-25T02:08:25.856767Z","steps":["trace[1937106593] 'range keys from in-memory index tree' (duration: 551.230801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.856852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.305083Z","time spent":"551.758223ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:25.856894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.868514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:25.856907Z","caller":"traceutil/trace.go:171","msg":"trace[575186335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"858.905975ms","start":"2026-02-25T02:08:24.997997Z","end":"2026-02-25T02:08:25.856903Z","steps":["trace[575186335] 'range keys from in-memory index tree' (duration: 858.783562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.85692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:24.997978Z","time spent":"858.938155ms","remote":"127.0.0.1:47296","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-25T02:08:25.856938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.744722ms","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":"info","ts":"2026-02-25T02:08:25.85703Z","caller":"traceutil/trace.go:171","msg":"trace[480061586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"551.893986ms","start":"2026-02-25T02:08:25.305112Z","end":"2026-02-25T02:08:25.857005Z","steps":["trace[480061586] 'range keys from in-memory index tree' (duration: 551.494256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.857088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.305082Z","time spent":"551.988668ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:08:26.162879Z","caller":"traceutil/trace.go:171","msg":"trace[867282625] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"301.40152ms","start":"2026-02-25T02:08:25.861446Z","end":"2026-02-25T02:08:26.162847Z","steps":["trace[867282625] 'process raft request' (duration: 301.304108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:26.163076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.861437Z","time spent":"301.542832ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:26.163278Z","caller":"traceutil/trace.go:171","msg":"trace[422546770] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"301.974082ms","start":"2026-02-25T02:08:25.86126Z","end":"2026-02-25T02:08:26.163235Z","steps":["trace[422546770] 'process raft request' (duration: 242.075365ms)","trace[422546770] 'compare' (duration: 58.964586ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:26.163287Z","caller":"traceutil/trace.go:171","msg":"trace[979719288] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"300.953901ms","start":"2026-02-25T02:08:25.86232Z","end":"2026-02-25T02:08:26.163274Z","steps":["trace[979719288] 'process raft request' (duration: 300.490411ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:26.163408Z","caller":"traceutil/trace.go:171","msg":"trace[1841923677] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"299.424017ms","start":"2026-02-25T02:08:25.863941Z","end":"2026-02-25T02:08:26.163365Z","steps":["trace[1841923677] 'process raft request' (duration: 299.242054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:26.163486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.862314Z","time spent":"301.139454ms","remote":"127.0.0.1:47598","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-25T02:08:26.163443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.861233Z","time spent":"302.107615ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:28.401787Z","caller":"traceutil/trace.go:171","msg":"trace[1727295918] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"403.672265ms","start":"2026-02-25T02:08:27.998052Z","end":"2026-02-25T02:08:28.401724Z","steps":["trace[1727295918] 'read index received' (duration: 403.295217ms)","trace[1727295918] 'applied index is now lower than readState.Index' (duration: 375.198µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:28.401907Z","caller":"traceutil/trace.go:171","msg":"trace[196451146] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"446.998115ms","start":"2026-02-25T02:08:27.954863Z","end":"2026-02-25T02:08:28.401861Z","steps":["trace[196451146] 'process raft request' (duration: 446.602256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.402125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.89056ms","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-25T02:08:28.40218Z","caller":"traceutil/trace.go:171","msg":"trace[1671999099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"234.029783ms","start":"2026-02-25T02:08:28.168137Z","end":"2026-02-25T02:08:28.402166Z","steps":["trace[1671999099] 'agreement among raft nodes before linearized reading' (duration: 233.798538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.402181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:27.954839Z","time spent":"447.22598ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:28.40221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.450236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T02:08:28.402045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.95935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:28.402339Z","caller":"traceutil/trace.go:171","msg":"trace[519659214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"216.6412ms","start":"2026-02-25T02:08:28.185674Z","end":"2026-02-25T02:08:28.402315Z","steps":["trace[519659214] 'agreement among raft nodes before linearized reading' (duration: 216.291903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:28.402416Z","caller":"traceutil/trace.go:171","msg":"trace[2099061691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"404.40148ms","start":"2026-02-25T02:08:27.997983Z","end":"2026-02-25T02:08:28.402384Z","steps":["trace[2099061691] 'agreement among raft nodes before linearized reading' (duration: 403.919679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.402305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.047673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T02:08:28.402488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:27.997913Z","time spent":"404.525973ms","remote":"127.0.0.1:47296","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-25T02:08:28.402518Z","caller":"traceutil/trace.go:171","msg":"trace[1754082675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"233.278487ms","start":"2026-02-25T02:08:28.169227Z","end":"2026-02-25T02:08:28.402505Z","steps":["trace[1754082675] 'agreement among raft nodes before linearized reading' (duration: 232.906369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.40228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.369209ms","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-25T02:08:28.402589Z","caller":"traceutil/trace.go:171","msg":"trace[308305686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"233.696376ms","start":"2026-02-25T02:08:28.168881Z","end":"2026-02-25T02:08:28.402578Z","steps":["trace[308305686] 'agreement among raft nodes before linearized reading' (duration: 233.254526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.775895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.29979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:28.776192Z","caller":"traceutil/trace.go:171","msg":"trace[361268704] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"368.195653ms","start":"2026-02-25T02:08:28.407978Z","end":"2026-02-25T02:08:28.776173Z","steps":["trace[361268704] 'process raft request' (duration: 368.113482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.7763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:28.407928Z","time spent":"368.313906ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:28.776273Z","caller":"traceutil/trace.go:171","msg":"trace[670627083] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"368.47326ms","start":"2026-02-25T02:08:28.407733Z","end":"2026-02-25T02:08:28.776206Z","steps":["trace[670627083] 'process raft request' (duration: 214.760531ms)","trace[670627083] 'compare' (duration: 152.84613ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:28.776259Z","caller":"traceutil/trace.go:171","msg":"trace[578302451] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"368.276156ms","start":"2026-02-25T02:08:28.407912Z","end":"2026-02-25T02:08:28.776188Z","steps":["trace[578302451] 'process raft request' (duration: 368.108742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:28.776449Z","caller":"traceutil/trace.go:171","msg":"trace[1111202887] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"367.57818ms","start":"2026-02-25T02:08:28.408828Z","end":"2026-02-25T02:08:28.776406Z","steps":["trace[1111202887] 'process raft request' (duration: 367.300864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:28.776499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:28.407712Z","time spent":"368.656823ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:28.776589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:28.407869Z","time spent":"368.533961ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:28.776656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:28.408633Z","time spent":"367.912658ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:41.299525Z","caller":"traceutil/trace.go:171","msg":"trace[1798230006] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"221.055484ms","start":"2026-02-25T02:08:41.078433Z","end":"2026-02-25T02:08:41.299489Z","steps":["trace[1798230006] 'process raft request' (duration: 220.82324ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:46.16395Z","caller":"traceutil/trace.go:171","msg":"trace[1055069133] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1920; }","duration":"160.481865ms","start":"2026-02-25T02:11:46.003439Z","end":"2026-02-25T02:11:46.16392Z","steps":["trace[1055069133] 'read index received' (duration: 123.087832ms)","trace[1055069133] 'applied index is now lower than readState.Index' (duration: 37.393063ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:46.164164Z","caller":"traceutil/trace.go:171","msg":"trace[654507888] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"166.627798ms","start":"2026-02-25T02:11:45.9975Z","end":"2026-02-25T02:11:46.164128Z","steps":["trace[654507888] 'process raft request' (duration: 166.395403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:46.164214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.752001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:11:46.164243Z","caller":"traceutil/trace.go:171","msg":"trace[1065218239] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1838; }","duration":"160.813892ms","start":"2026-02-25T02:11:46.003422Z","end":"2026-02-25T02:11:46.164235Z","steps":["trace[1065218239] 'agreement among raft nodes before linearized reading' (duration: 160.746361ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:46.164516Z","caller":"traceutil/trace.go:171","msg":"trace[1935482445] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"168.941816ms","start":"2026-02-25T02:11:45.995557Z","end":"2026-02-25T02:11:46.164498Z","steps":["trace[1935482445] 'process raft request' (duration: 168.277302ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:46.164441Z","caller":"traceutil/trace.go:171","msg":"trace[1507609668] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"169.083159ms","start":"2026-02-25T02:11:45.995344Z","end":"2026-02-25T02:11:46.164427Z","steps":["trace[1507609668] 'process raft request' (duration: 131.264767ms)","trace[1507609668] 'compare' (duration: 37.102227ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:47.246106Z","caller":"traceutil/trace.go:171","msg":"trace[452516438] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"246.436869ms","start":"2026-02-25T02:11:46.999639Z","end":"2026-02-25T02:11:47.246076Z","steps":["trace[452516438] 'read index received' (duration: 246.105032ms)","trace[452516438] 'applied index is now lower than readState.Index' (duration: 330.257µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:47.246255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.606523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:11:47.24636Z","caller":"traceutil/trace.go:171","msg":"trace[2091059243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"246.813117ms","start":"2026-02-25T02:11:46.999531Z","end":"2026-02-25T02:11:47.246344Z","steps":["trace[2091059243] 'agreement among raft nodes before linearized reading' (duration: 246.665254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:47.247009Z","caller":"traceutil/trace.go:171","msg":"trace[1666772205] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"385.575805ms","start":"2026-02-25T02:11:46.861399Z","end":"2026-02-25T02:11:47.246974Z","steps":["trace[1666772205] 'process raft request' (duration: 384.505372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:47.247339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:46.861357Z","time spent":"385.731768ms","remote":"127.0.0.1:47598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}