{"level":"warn","ts":"2026-04-01T15:26:51.314239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:26:51.314368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-04-01T15:26:51.314445Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:26:51.314461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-01T15:26:51.314488Z","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-04-01T15:26:51.315253Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-01T15:26:51.315334Z","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":16,"max-cpu-available":16,"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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-04-01T15:26:51.319171Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.633838ms"} {"level":"info","ts":"2026-04-01T15:26:51.328321Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-01T15:26:51.328442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T15:26:51.328484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-01T15:26:51.328501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T15:26:51.328515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-01T15:26:51.328579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-01T15:26:51.334665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T15:26:51.336203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T15:26:51.337597Z","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-04-01T15:26:51.341411Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T15:26:51.341704Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T15:26:51.341904Z","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-04-01T15:26:51.342026Z","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-04-01T15:26:51.342046Z","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-04-01T15:26:51.344422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-01T15:26:51.344627Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-01T15:26:51.346601Z","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-04-01T15:26:51.346832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-01T15:26:51.346913Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T15:26:51.346958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T15:26:51.346893Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-01T15:26:51.928995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T15:26:51.92908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T15:26:51.929129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-01T15:26:51.929152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-01T15:26:51.929166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-01T15:26:51.929185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-01T15:26:51.929208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-01T15:26:51.930879Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T15:26:51.930885Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:26:51.930927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:26:51.931065Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:26:51.931232Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T15:26:51.931369Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T15:26:51.93211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:26:51.93235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:26:51.932399Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:26:51.933995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-01T15:26:51.938596Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T15:27:13.958126Z","caller":"traceutil/trace.go:171","msg":"trace[760168978] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"226.280931ms","start":"2026-04-01T15:27:13.73182Z","end":"2026-04-01T15:27:13.958101Z","steps":["trace[760168978] 'read index received' (duration: 226.085926ms)","trace[760168978] 'applied index is now lower than readState.Index' (duration: 193.895µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:13.95837Z","caller":"traceutil/trace.go:171","msg":"trace[1843148607] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"436.362628ms","start":"2026-04-01T15:27:13.521894Z","end":"2026-04-01T15:27:13.958256Z","steps":["trace[1843148607] 'process raft request' (duration: 436.054151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:13.958472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.476046ms","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-04-01T15:27:13.958556Z","caller":"traceutil/trace.go:171","msg":"trace[1280019489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"226.749652ms","start":"2026-04-01T15:27:13.731791Z","end":"2026-04-01T15:27:13.958541Z","steps":["trace[1280019489] 'agreement among raft nodes before linearized reading' (duration: 226.467215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:13.958429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.285635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:27:13.95865Z","caller":"traceutil/trace.go:171","msg":"trace[1428477974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"220.598962ms","start":"2026-04-01T15:27:13.738027Z","end":"2026-04-01T15:27:13.958626Z","steps":["trace[1428477974] 'agreement among raft nodes before linearized reading' (duration: 220.276294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:13.959407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:13.521871Z","time spent":"436.671695ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:27:14.465781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.150737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:27:14.465999Z","caller":"traceutil/trace.go:171","msg":"trace[465334408] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"501.998803ms","start":"2026-04-01T15:27:13.963982Z","end":"2026-04-01T15:27:14.465981Z","steps":["trace[465334408] 'process raft request' (duration: 501.911011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:14.466072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:13.963969Z","time spent":"502.072385ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:14.466074Z","caller":"traceutil/trace.go:171","msg":"trace[2092542916] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"503.240193ms","start":"2026-04-01T15:27:13.962794Z","end":"2026-04-01T15:27:14.466034Z","steps":["trace[2092542916] 'process raft request' (duration: 51.549633ms)","trace[2092542916] 'compare' (duration: 450.779958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:14.466396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:13.962769Z","time spent":"503.372137ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:14.469343Z","caller":"traceutil/trace.go:171","msg":"trace[938576319] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"251.409291ms","start":"2026-04-01T15:27:14.217917Z","end":"2026-04-01T15:27:14.469326Z","steps":["trace[938576319] 'read index received' (duration: 251.261588ms)","trace[938576319] 'applied index is now lower than readState.Index' (duration: 146.973µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:14.469453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.519134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:14.469495Z","caller":"traceutil/trace.go:171","msg":"trace[280942149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"251.604726ms","start":"2026-04-01T15:27:14.217884Z","end":"2026-04-01T15:27:14.469488Z","steps":["trace[280942149] 'agreement among raft nodes before linearized reading' (duration: 251.513754ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:14.469546Z","caller":"traceutil/trace.go:171","msg":"trace[352636101] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"284.484916ms","start":"2026-04-01T15:27:14.185025Z","end":"2026-04-01T15:27:14.46951Z","steps":["trace[352636101] 'process raft request' (duration: 284.194439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:19.910279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842447461860415,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T15:27:19.932046Z","caller":"traceutil/trace.go:171","msg":"trace[601607399] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"619.623593ms","start":"2026-04-01T15:27:19.312403Z","end":"2026-04-01T15:27:19.932027Z","steps":["trace[601607399] 'process raft request' (duration: 619.422248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:19.932159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:19.31235Z","time spent":"619.750446ms","remote":"127.0.0.1:39046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:27:19.93228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.373909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T15:27:19.932327Z","caller":"traceutil/trace.go:171","msg":"trace[803902824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"522.42856ms","start":"2026-04-01T15:27:19.409884Z","end":"2026-04-01T15:27:19.932312Z","steps":["trace[803902824] 'agreement among raft nodes before linearized reading' (duration: 522.295417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:19.932361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:19.409825Z","time spent":"522.528823ms","remote":"127.0.0.1:39228","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-04-01T15:27:19.931989Z","caller":"traceutil/trace.go:171","msg":"trace[1184482052] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"522.062652ms","start":"2026-04-01T15:27:19.409902Z","end":"2026-04-01T15:27:19.931964Z","steps":["trace[1184482052] 'read index received' (duration: 521.844006ms)","trace[1184482052] 'applied index is now lower than readState.Index' (duration: 217.876µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:20.251489Z","caller":"traceutil/trace.go:171","msg":"trace[616323713] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"265.373602ms","start":"2026-04-01T15:27:19.986093Z","end":"2026-04-01T15:27:20.251467Z","steps":["trace[616323713] 'read index received' (duration: 194.434747ms)","trace[616323713] 'applied index is now lower than readState.Index' (duration: 70.937074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:20.251674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.564166ms","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-04-01T15:27:20.251717Z","caller":"traceutil/trace.go:171","msg":"trace[686802955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"265.647588ms","start":"2026-04-01T15:27:19.986057Z","end":"2026-04-01T15:27:20.251704Z","steps":["trace[686802955] 'agreement among raft nodes before linearized reading' (duration: 265.568816ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:20.251789Z","caller":"traceutil/trace.go:171","msg":"trace[1290011725] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"314.711801ms","start":"2026-04-01T15:27:19.936984Z","end":"2026-04-01T15:27:20.251696Z","steps":["trace[1290011725] 'process raft request' (duration: 243.713315ms)","trace[1290011725] 'compare' (duration: 70.665958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:20.251986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:19.936966Z","time spent":"314.935126ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:21.450863Z","caller":"traceutil/trace.go:171","msg":"trace[725411164] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"450.626395ms","start":"2026-04-01T15:27:21.000206Z","end":"2026-04-01T15:27:21.450832Z","steps":["trace[725411164] 'process raft request' (duration: 450.322947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:21.451094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:21.000177Z","time spent":"450.833089ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:21.459783Z","caller":"traceutil/trace.go:171","msg":"trace[422856230] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"241.719186ms","start":"2026-04-01T15:27:21.21804Z","end":"2026-04-01T15:27:21.459759Z","steps":["trace[422856230] 'read index received' (duration: 232.706147ms)","trace[422856230] 'applied index is now lower than readState.Index' (duration: 9.012029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:21.45995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.900701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:21.459991Z","caller":"traceutil/trace.go:171","msg":"trace[557247233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"241.990672ms","start":"2026-04-01T15:27:21.217988Z","end":"2026-04-01T15:27:21.459979Z","steps":["trace[557247233] 'agreement among raft nodes before linearized reading' (duration: 241.8961ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:21.460037Z","caller":"traceutil/trace.go:171","msg":"trace[1789525039] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"406.610294ms","start":"2026-04-01T15:27:21.053393Z","end":"2026-04-01T15:27:21.460004Z","steps":["trace[1789525039] 'process raft request' (duration: 406.239226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:21.460182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:21.05337Z","time spent":"406.745277ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:27:23.740322Z","caller":"traceutil/trace.go:171","msg":"trace[1847041621] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"144.971795ms","start":"2026-04-01T15:27:23.595264Z","end":"2026-04-01T15:27:23.740236Z","steps":["trace[1847041621] 'process raft request' (duration: 144.833382ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:24.028363Z","caller":"traceutil/trace.go:171","msg":"trace[326413767] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"283.566353ms","start":"2026-04-01T15:27:23.744775Z","end":"2026-04-01T15:27:24.028341Z","steps":["trace[326413767] 'process raft request' (duration: 283.43912ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:25.306973Z","caller":"traceutil/trace.go:171","msg":"trace[1280463634] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"111.173802ms","start":"2026-04-01T15:27:25.195769Z","end":"2026-04-01T15:27:25.306942Z","steps":["trace[1280463634] 'process raft request' (duration: 109.755858ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:28.12724Z","caller":"traceutil/trace.go:171","msg":"trace[781128322] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"466.09198ms","start":"2026-04-01T15:27:27.661123Z","end":"2026-04-01T15:27:28.127215Z","steps":["trace[781128322] 'read index received' (duration: 465.178318ms)","trace[781128322] 'applied index is now lower than readState.Index' (duration: 912.662µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:28.127376Z","caller":"traceutil/trace.go:171","msg":"trace[1157505265] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"493.321853ms","start":"2026-04-01T15:27:27.634009Z","end":"2026-04-01T15:27:28.127331Z","steps":["trace[1157505265] 'process raft request' (duration: 492.677567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:28.127553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:27.633975Z","time spent":"493.488697ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:27:28.127677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.542651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-85htw\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-01T15:27:28.127782Z","caller":"traceutil/trace.go:171","msg":"trace[356506310] range","detail":"{range_begin:/registry/pods/kube-system/cilium-85htw; range_end:; response_count:1; response_revision:614; }","duration":"466.687554ms","start":"2026-04-01T15:27:27.661076Z","end":"2026-04-01T15:27:28.127764Z","steps":["trace[356506310] 'agreement among raft nodes before linearized reading' (duration: 466.266074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:28.127834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:27.661054Z","time spent":"466.766846ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-85htw\" "} {"level":"info","ts":"2026-04-01T15:27:28.469861Z","caller":"traceutil/trace.go:171","msg":"trace[2121365565] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"312.598078ms","start":"2026-04-01T15:27:28.157239Z","end":"2026-04-01T15:27:28.469837Z","steps":["trace[2121365565] 'process raft request' (duration: 312.458515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:28.470014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:28.15722Z","time spent":"312.724422ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:28.489376Z","caller":"traceutil/trace.go:171","msg":"trace[47959763] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"272.279438ms","start":"2026-04-01T15:27:28.217056Z","end":"2026-04-01T15:27:28.489335Z","steps":["trace[47959763] 'read index received' (duration: 252.61024ms)","trace[47959763] 'applied index is now lower than readState.Index' (duration: 19.667688ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:28.489538Z","caller":"traceutil/trace.go:171","msg":"trace[2043544921] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"299.800067ms","start":"2026-04-01T15:27:28.189708Z","end":"2026-04-01T15:27:28.489508Z","steps":["trace[2043544921] 'process raft request' (duration: 299.460269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:28.489704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.613627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:28.489774Z","caller":"traceutil/trace.go:171","msg":"trace[927758589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"272.731239ms","start":"2026-04-01T15:27:28.217024Z","end":"2026-04-01T15:27:28.489755Z","steps":["trace[927758589] 'agreement among raft nodes before linearized reading' (duration: 272.551765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:28.489774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.389434ms","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-04-01T15:27:28.489776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.172127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T15:27:28.489838Z","caller":"traceutil/trace.go:171","msg":"trace[288785534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"203.470787ms","start":"2026-04-01T15:27:28.28635Z","end":"2026-04-01T15:27:28.48982Z","steps":["trace[288785534] 'agreement among raft nodes before linearized reading' (duration: 203.292401ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:28.489846Z","caller":"traceutil/trace.go:171","msg":"trace[1336541534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"205.280461ms","start":"2026-04-01T15:27:28.28455Z","end":"2026-04-01T15:27:28.489831Z","steps":["trace[1336541534] 'agreement among raft nodes before linearized reading' (duration: 205.063925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:29.078896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842447461860620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:27:29.579945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842447461860620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:27:29.623608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.015808383s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T15:27:30.425864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.990946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:27:30.426908Z","caller":"traceutil/trace.go:171","msg":"trace[187465266] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"1.848407073s","start":"2026-04-01T15:27:28.578455Z","end":"2026-04-01T15:27:30.426862Z","steps":["trace[187465266] 'read index received' (duration: 1.045338191s)","trace[187465266] 'applied index is now lower than readState.Index' (duration: 803.064212ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:30.426596Z","caller":"traceutil/trace.go:171","msg":"trace[2112801854] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.852742237s","start":"2026-04-01T15:27:28.573807Z","end":"2026-04-01T15:27:30.426549Z","steps":["trace[2112801854] 'process raft request' (duration: 1.049994403s)","trace[2112801854] 'compare' (duration: 801.900294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:27:30.42739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:28.573784Z","time spent":"1.853375694s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:27:30.427681Z","caller":"traceutil/trace.go:171","msg":"trace[1745096452] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.853579087s","start":"2026-04-01T15:27:28.574052Z","end":"2026-04-01T15:27:30.427631Z","steps":["trace[1745096452] 'process raft request' (duration: 1.851898007s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:30.427851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:28.574027Z","time spent":"1.853736711s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:27:30.43817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859689987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:30.438239Z","caller":"traceutil/trace.go:171","msg":"trace[998590435] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:620; }","duration":"1.85979414s","start":"2026-04-01T15:27:28.57843Z","end":"2026-04-01T15:27:30.438224Z","steps":["trace[998590435] 'agreement among raft nodes before linearized reading' (duration: 1.848552737s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:30.438272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:28.578418Z","time spent":"1.859847371s","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-01T15:27:30.438261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220779636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:30.438451Z","caller":"traceutil/trace.go:171","msg":"trace[2101352752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.221019792s","start":"2026-04-01T15:27:29.217412Z","end":"2026-04-01T15:27:30.438432Z","steps":["trace[2101352752] 'agreement among raft nodes before linearized reading' (duration: 1.220651603s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:30.438532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:29.217389Z","time spent":"1.221130375s","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T15:27:32.147824Z","caller":"traceutil/trace.go:171","msg":"trace[218000593] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"318.394861ms","start":"2026-04-01T15:27:31.829405Z","end":"2026-04-01T15:27:32.147799Z","steps":["trace[218000593] 'process raft request' (duration: 318.248358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:32.147981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:31.829383Z","time spent":"318.536614ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:27:32.151277Z","caller":"traceutil/trace.go:171","msg":"trace[613844272] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"113.352663ms","start":"2026-04-01T15:27:32.037905Z","end":"2026-04-01T15:27:32.151257Z","steps":["trace[613844272] 'read index received' (duration: 109.805226ms)","trace[613844272] 'applied index is now lower than readState.Index' (duration: 3.546487ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:27:32.151358Z","caller":"traceutil/trace.go:171","msg":"trace[528254812] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"119.796049ms","start":"2026-04-01T15:27:32.031542Z","end":"2026-04-01T15:27:32.151338Z","steps":["trace[528254812] 'process raft request' (duration: 119.624195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:32.15152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.563828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:32.151584Z","caller":"traceutil/trace.go:171","msg":"trace[1353074048] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:649; }","duration":"113.64945ms","start":"2026-04-01T15:27:32.037921Z","end":"2026-04-01T15:27:32.151571Z","steps":["trace[1353074048] 'agreement among raft nodes before linearized reading' (duration: 113.522527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:32.151604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.07175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:27:32.151682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.962521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:32.151724Z","caller":"traceutil/trace.go:171","msg":"trace[403229496] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:649; }","duration":"100.221773ms","start":"2026-04-01T15:27:32.051475Z","end":"2026-04-01T15:27:32.151697Z","steps":["trace[403229496] 'agreement among raft nodes before linearized reading' (duration: 100.011438ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:32.151769Z","caller":"traceutil/trace.go:171","msg":"trace[1460278555] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:649; }","duration":"101.077255ms","start":"2026-04-01T15:27:32.050667Z","end":"2026-04-01T15:27:32.151744Z","steps":["trace[1460278555] 'agreement among raft nodes before linearized reading' (duration: 100.933301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:32.151786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.857555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:27:32.151827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.167826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:32.151863Z","caller":"traceutil/trace.go:171","msg":"trace[1701332282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:649; }","duration":"113.977768ms","start":"2026-04-01T15:27:32.037863Z","end":"2026-04-01T15:27:32.15184Z","steps":["trace[1701332282] 'agreement among raft nodes before linearized reading' (duration: 113.873115ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:32.151882Z","caller":"traceutil/trace.go:171","msg":"trace[200357619] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:649; }","duration":"101.261659ms","start":"2026-04-01T15:27:32.050613Z","end":"2026-04-01T15:27:32.151874Z","steps":["trace[200357619] 'agreement among raft nodes before linearized reading' (duration: 101.170977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:32.152152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.223364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:27:32.152225Z","caller":"traceutil/trace.go:171","msg":"trace[859322427] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:649; }","duration":"114.340947ms","start":"2026-04-01T15:27:32.037863Z","end":"2026-04-01T15:27:32.152204Z","steps":["trace[859322427] 'agreement among raft nodes before linearized reading' (duration: 113.554388ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:34.984504Z","caller":"traceutil/trace.go:171","msg":"trace[66831404] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"135.604923ms","start":"2026-04-01T15:27:34.848873Z","end":"2026-04-01T15:27:34.984478Z","steps":["trace[66831404] 'process raft request' (duration: 134.751063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:34.984569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.159507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-01T15:27:34.984658Z","caller":"traceutil/trace.go:171","msg":"trace[1674194554] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:693; }","duration":"157.30816ms","start":"2026-04-01T15:27:34.827331Z","end":"2026-04-01T15:27:34.984639Z","steps":["trace[1674194554] 'range keys from in-memory index tree' (duration: 156.998943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:46.593684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.052689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2mg76\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-01T15:27:46.593785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.697562ms","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-04-01T15:27:46.593823Z","caller":"traceutil/trace.go:171","msg":"trace[1198601114] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2mg76; range_end:; response_count:1; response_revision:779; }","duration":"826.273575ms","start":"2026-04-01T15:27:45.767518Z","end":"2026-04-01T15:27:46.593792Z","steps":["trace[1198601114] 'range keys from in-memory index tree' (duration: 825.788193ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:46.593887Z","caller":"traceutil/trace.go:171","msg":"trace[411133719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"893.866976ms","start":"2026-04-01T15:27:45.699997Z","end":"2026-04-01T15:27:46.593864Z","steps":["trace[411133719] 'range keys from in-memory index tree' (duration: 893.411474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:27:46.59389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:45.76747Z","time spent":"826.403908ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2mg76\" "} {"level":"warn","ts":"2026-04-01T15:27:46.593933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:27:45.699972Z","time spent":"893.952498ms","remote":"127.0.0.1:39228","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-04-01T15:27:46.730587Z","caller":"traceutil/trace.go:171","msg":"trace[2095415070] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"133.791159ms","start":"2026-04-01T15:27:46.596767Z","end":"2026-04-01T15:27:46.730558Z","steps":["trace[2095415070] 'process raft request' (duration: 133.624515ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:46.738155Z","caller":"traceutil/trace.go:171","msg":"trace[2051451909] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"141.062416ms","start":"2026-04-01T15:27:46.597057Z","end":"2026-04-01T15:27:46.73812Z","steps":["trace[2051451909] 'process raft request' (duration: 140.572254ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:46.738233Z","caller":"traceutil/trace.go:171","msg":"trace[156178854] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"140.643197ms","start":"2026-04-01T15:27:46.597574Z","end":"2026-04-01T15:27:46.738217Z","steps":["trace[156178854] 'process raft request' (duration: 140.390621ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:46.738334Z","caller":"traceutil/trace.go:171","msg":"trace[1974120908] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"139.499768ms","start":"2026-04-01T15:27:46.598808Z","end":"2026-04-01T15:27:46.738308Z","steps":["trace[1974120908] 'process raft request' (duration: 139.290373ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:27:46.738446Z","caller":"traceutil/trace.go:171","msg":"trace[1817428693] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"137.263034ms","start":"2026-04-01T15:27:46.601124Z","end":"2026-04-01T15:27:46.738387Z","steps":["trace[1817428693] 'process raft request' (duration: 137.044188ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:28:13.450981Z","caller":"traceutil/trace.go:171","msg":"trace[150066628] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"501.447887ms","start":"2026-04-01T15:28:12.949503Z","end":"2026-04-01T15:28:13.450951Z","steps":["trace[150066628] 'process raft request' (duration: 501.213491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:28:13.451168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:28:12.949493Z","time spent":"501.58561ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:28:36.394786Z","caller":"traceutil/trace.go:171","msg":"trace[1711062890] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"110.423434ms","start":"2026-04-01T15:28:36.284329Z","end":"2026-04-01T15:28:36.394752Z","steps":["trace[1711062890] 'process raft request' (duration: 110.205669ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:28:59.904367Z","caller":"traceutil/trace.go:171","msg":"trace[1950298070] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"126.791572ms","start":"2026-04-01T15:28:59.777545Z","end":"2026-04-01T15:28:59.904336Z","steps":["trace[1950298070] 'process raft request' (duration: 126.548285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:00.652361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.55768ms","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-04-01T15:29:00.652454Z","caller":"traceutil/trace.go:171","msg":"trace[588989775] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1852; }","duration":"340.703853ms","start":"2026-04-01T15:29:00.31173Z","end":"2026-04-01T15:29:00.652434Z","steps":["trace[588989775] 'count revisions from in-memory index tree' (duration: 340.467678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:00.652499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:00.311706Z","time spent":"340.781945ms","remote":"127.0.0.1:48684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:29:00.652484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.260229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:00.652681Z","caller":"traceutil/trace.go:171","msg":"trace[857360889] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1852; }","duration":"223.424733ms","start":"2026-04-01T15:29:00.429139Z","end":"2026-04-01T15:29:00.652563Z","steps":["trace[857360889] 'count revisions from in-memory index tree' (duration: 223.144136ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:00.785586Z","caller":"traceutil/trace.go:171","msg":"trace[1578216080] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"231.134889ms","start":"2026-04-01T15:29:00.554407Z","end":"2026-04-01T15:29:00.785541Z","steps":["trace[1578216080] 'read index received' (duration: 138.895674ms)","trace[1578216080] 'applied index is now lower than readState.Index' (duration: 92.237515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:29:00.785828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.399895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-01T15:29:00.785896Z","caller":"traceutil/trace.go:171","msg":"trace[344711545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"231.525438ms","start":"2026-04-01T15:29:00.554354Z","end":"2026-04-01T15:29:00.78588Z","steps":["trace[344711545] 'agreement among raft nodes before linearized reading' (duration: 231.304732ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:01.526024Z","caller":"traceutil/trace.go:171","msg":"trace[1715814103] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"339.203496ms","start":"2026-04-01T15:29:01.186793Z","end":"2026-04-01T15:29:01.525997Z","steps":["trace[1715814103] 'read index received' (duration: 338.887719ms)","trace[1715814103] 'applied index is now lower than readState.Index' (duration: 314.347µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:01.526181Z","caller":"traceutil/trace.go:171","msg":"trace[165621822] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"375.982407ms","start":"2026-04-01T15:29:01.150162Z","end":"2026-04-01T15:29:01.526144Z","steps":["trace[165621822] 'process raft request' (duration: 375.665029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:01.526265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.446672ms","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-04-01T15:29:01.526299Z","caller":"traceutil/trace.go:171","msg":"trace[718478875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1853; }","duration":"339.520803ms","start":"2026-04-01T15:29:01.186768Z","end":"2026-04-01T15:29:01.526289Z","steps":["trace[718478875] 'agreement among raft nodes before linearized reading' (duration: 339.3708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:01.526356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.186757Z","time spent":"339.576535ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T15:29:01.526352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.15014Z","time spent":"376.13029ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:01.526427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.515952ms","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-04-01T15:29:01.52649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.927079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:01.526518Z","caller":"traceutil/trace.go:171","msg":"trace[1901437560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1853; }","duration":"336.696916ms","start":"2026-04-01T15:29:01.1898Z","end":"2026-04-01T15:29:01.526497Z","steps":["trace[1901437560] 'agreement among raft nodes before linearized reading' (duration: 336.413998ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:01.526561Z","caller":"traceutil/trace.go:171","msg":"trace[380146785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"308.01492ms","start":"2026-04-01T15:29:01.218526Z","end":"2026-04-01T15:29:01.526541Z","steps":["trace[380146785] 'agreement among raft nodes before linearized reading' (duration: 307.908658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:01.526566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.189774Z","time spent":"336.783238ms","remote":"127.0.0.1:39228","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-04-01T15:29:01.526579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.333003ms","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-04-01T15:29:01.526625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.667122ms","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-04-01T15:29:01.526682Z","caller":"traceutil/trace.go:171","msg":"trace[907994797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"328.747683ms","start":"2026-04-01T15:29:01.197909Z","end":"2026-04-01T15:29:01.526657Z","steps":["trace[907994797] 'agreement among raft nodes before linearized reading' (duration: 328.577389ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:01.526678Z","caller":"traceutil/trace.go:171","msg":"trace[980530437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1853; }","duration":"328.460797ms","start":"2026-04-01T15:29:01.198192Z","end":"2026-04-01T15:29:01.526652Z","steps":["trace[980530437] 'agreement among raft nodes before linearized reading' (duration: 328.198231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:01.526721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.197898Z","time spent":"328.815555ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T15:29:01.526723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.788335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T15:29:01.526735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.198182Z","time spent":"328.543899ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T15:29:01.526615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.218515Z","time spent":"308.088843ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T15:29:01.526784Z","caller":"traceutil/trace.go:171","msg":"trace[690953093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"328.927388ms","start":"2026-04-01T15:29:01.19784Z","end":"2026-04-01T15:29:01.526767Z","steps":["trace[690953093] 'agreement among raft nodes before linearized reading' (duration: 328.693732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:01.526854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:01.197784Z","time spent":"329.04265ms","remote":"127.0.0.1:39228","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-04-01T15:29:13.82587Z","caller":"traceutil/trace.go:171","msg":"trace[647775524] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"236.520068ms","start":"2026-04-01T15:29:13.589314Z","end":"2026-04-01T15:29:13.825834Z","steps":["trace[647775524] 'process raft request' (duration: 236.336764ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.859757Z","caller":"traceutil/trace.go:171","msg":"trace[1784241010] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"269.20194ms","start":"2026-04-01T15:29:13.590523Z","end":"2026-04-01T15:29:13.859725Z","steps":["trace[1784241010] 'process raft request' (duration: 269.003646ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.860118Z","caller":"traceutil/trace.go:171","msg":"trace[1819973213] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"268.853062ms","start":"2026-04-01T15:29:13.591229Z","end":"2026-04-01T15:29:13.860082Z","steps":["trace[1819973213] 'process raft request' (duration: 268.455602ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.860165Z","caller":"traceutil/trace.go:171","msg":"trace[1243845762] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"184.025206ms","start":"2026-04-01T15:29:13.676114Z","end":"2026-04-01T15:29:13.860139Z","steps":["trace[1243845762] 'process raft request' (duration: 183.949834ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.860282Z","caller":"traceutil/trace.go:171","msg":"trace[2105269868] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2030; }","duration":"267.342075ms","start":"2026-04-01T15:29:13.592913Z","end":"2026-04-01T15:29:13.860255Z","steps":["trace[2105269868] 'read index received' (duration: 232.771298ms)","trace[2105269868] 'applied index is now lower than readState.Index' (duration: 34.569637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:29:13.860474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.54223ms","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-04-01T15:29:13.860508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.661828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:13.86052Z","caller":"traceutil/trace.go:171","msg":"trace[1166928785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1961; }","duration":"267.633122ms","start":"2026-04-01T15:29:13.592876Z","end":"2026-04-01T15:29:13.860509Z","steps":["trace[1166928785] 'agreement among raft nodes before linearized reading' (duration: 267.472918ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.860548Z","caller":"traceutil/trace.go:171","msg":"trace[1868782196] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1961; }","duration":"100.72311ms","start":"2026-04-01T15:29:13.759813Z","end":"2026-04-01T15:29:13.860536Z","steps":["trace[1868782196] 'agreement among raft nodes before linearized reading' (duration: 100.628847ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:13.860766Z","caller":"traceutil/trace.go:171","msg":"trace[668352693] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"268.77816ms","start":"2026-04-01T15:29:13.591386Z","end":"2026-04-01T15:29:13.860165Z","steps":["trace[668352693] 'process raft request' (duration: 268.569355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:15.70248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.186416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:29:15.702598Z","caller":"traceutil/trace.go:171","msg":"trace[1484677510] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"182.529441ms","start":"2026-04-01T15:29:15.520046Z","end":"2026-04-01T15:29:15.702575Z","steps":["trace[1484677510] 'process raft request' (duration: 35.150721ms)","trace[1484677510] 'compare' (duration: 147.062323ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:15.736457Z","caller":"traceutil/trace.go:171","msg":"trace[1857902999] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"214.463695ms","start":"2026-04-01T15:29:15.521972Z","end":"2026-04-01T15:29:15.736436Z","steps":["trace[1857902999] 'process raft request' (duration: 214.389134ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:15.736489Z","caller":"traceutil/trace.go:171","msg":"trace[104200993] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"216.152776ms","start":"2026-04-01T15:29:15.5203Z","end":"2026-04-01T15:29:15.736453Z","steps":["trace[104200993] 'process raft request' (duration: 215.827108ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:15.736623Z","caller":"traceutil/trace.go:171","msg":"trace[1349325996] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"215.49442ms","start":"2026-04-01T15:29:15.521102Z","end":"2026-04-01T15:29:15.736597Z","steps":["trace[1349325996] 'process raft request' (duration: 215.156012ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:15.859865Z","caller":"traceutil/trace.go:171","msg":"trace[887164117] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"114.079893ms","start":"2026-04-01T15:29:15.745717Z","end":"2026-04-01T15:29:15.859797Z","steps":["trace[887164117] 'process raft request' (duration: 91.21211ms)","trace[887164117] 'compare' (duration: 22.601527ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:16.004218Z","caller":"traceutil/trace.go:171","msg":"trace[1300402344] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"137.994623ms","start":"2026-04-01T15:29:15.866192Z","end":"2026-04-01T15:29:16.004186Z","steps":["trace[1300402344] 'process raft request' (duration: 116.839351ms)","trace[1300402344] 'compare' (duration: 20.878986ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:16.004249Z","caller":"traceutil/trace.go:171","msg":"trace[344224359] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"136.870385ms","start":"2026-04-01T15:29:15.867351Z","end":"2026-04-01T15:29:16.004221Z","steps":["trace[344224359] 'process raft request' (duration: 136.718132ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:16.004288Z","caller":"traceutil/trace.go:171","msg":"trace[219821728] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2054; }","duration":"127.620362ms","start":"2026-04-01T15:29:15.876651Z","end":"2026-04-01T15:29:16.004271Z","steps":["trace[219821728] 'read index received' (duration: 106.345846ms)","trace[219821728] 'applied index is now lower than readState.Index' (duration: 21.273655ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:16.004431Z","caller":"traceutil/trace.go:171","msg":"trace[404568613] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"136.008965ms","start":"2026-04-01T15:29:15.868404Z","end":"2026-04-01T15:29:16.004413Z","steps":["trace[404568613] 'process raft request' (duration: 135.830111ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:16.004488Z","caller":"traceutil/trace.go:171","msg":"trace[2070392236] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"136.799094ms","start":"2026-04-01T15:29:15.867664Z","end":"2026-04-01T15:29:16.004463Z","steps":["trace[2070392236] 'process raft request' (duration: 136.453446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:16.004786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.119324ms","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-04-01T15:29:16.004841Z","caller":"traceutil/trace.go:171","msg":"trace[750060215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1983; }","duration":"128.216186ms","start":"2026-04-01T15:29:15.87661Z","end":"2026-04-01T15:29:16.004827Z","steps":["trace[750060215] 'agreement among raft nodes before linearized reading' (duration: 128.007571ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:16.005532Z","caller":"traceutil/trace.go:171","msg":"trace[1636080359] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"137.575222ms","start":"2026-04-01T15:29:15.867931Z","end":"2026-04-01T15:29:16.005507Z","steps":["trace[1636080359] 'process raft request' (duration: 136.261931ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:18.506747Z","caller":"traceutil/trace.go:171","msg":"trace[1016209308] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"497.49591ms","start":"2026-04-01T15:29:18.009225Z","end":"2026-04-01T15:29:18.506721Z","steps":["trace[1016209308] 'read index received' (duration: 497.351427ms)","trace[1016209308] 'applied index is now lower than readState.Index' (duration: 143.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:18.506866Z","caller":"traceutil/trace.go:171","msg":"trace[622465078] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"630.91403ms","start":"2026-04-01T15:29:17.875942Z","end":"2026-04-01T15:29:18.506856Z","steps":["trace[622465078] 'process raft request' (duration: 630.591073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.506991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:17.875923Z","time spent":"630.972672ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:18.507093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.849779ms","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-04-01T15:29:18.507164Z","caller":"traceutil/trace.go:171","msg":"trace[659063531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1989; }","duration":"497.964221ms","start":"2026-04-01T15:29:18.009184Z","end":"2026-04-01T15:29:18.507148Z","steps":["trace[659063531] 'agreement among raft nodes before linearized reading' (duration: 497.786177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.507204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.009167Z","time spent":"498.027863ms","remote":"127.0.0.1:39228","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":"info","ts":"2026-04-01T15:29:18.523537Z","caller":"traceutil/trace.go:171","msg":"trace[281290457] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"513.363774ms","start":"2026-04-01T15:29:18.010144Z","end":"2026-04-01T15:29:18.523508Z","steps":["trace[281290457] 'process raft request' (duration: 513.131078ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:18.523734Z","caller":"traceutil/trace.go:171","msg":"trace[110655138] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"513.538508ms","start":"2026-04-01T15:29:18.010178Z","end":"2026-04-01T15:29:18.523716Z","steps":["trace[110655138] 'process raft request' (duration: 513.250641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.523821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.010161Z","time spent":"513.61035ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:18.523822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.01013Z","time spent":"513.505927ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:29:18.524067Z","caller":"traceutil/trace.go:171","msg":"trace[1838607913] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"302.136988ms","start":"2026-04-01T15:29:18.221921Z","end":"2026-04-01T15:29:18.524058Z","steps":["trace[1838607913] 'process raft request' (duration: 301.730618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.524113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.221896Z","time spent":"302.1961ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:18.524118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.80356ms","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-04-01T15:29:18.524141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.453769ms","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-04-01T15:29:18.52412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.253359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:29:18.524222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.447248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-01T15:29:18.524227Z","caller":"traceutil/trace.go:171","msg":"trace[1806709762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1993; }","duration":"514.929903ms","start":"2026-04-01T15:29:18.009246Z","end":"2026-04-01T15:29:18.524176Z","steps":["trace[1806709762] 'agreement among raft nodes before linearized reading' (duration: 514.734818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.524239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.415727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:18.524262Z","caller":"traceutil/trace.go:171","msg":"trace[1494016774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1993; }","duration":"342.461874ms","start":"2026-04-01T15:29:18.181776Z","end":"2026-04-01T15:29:18.524238Z","steps":["trace[1494016774] 'agreement among raft nodes before linearized reading' (duration: 342.216779ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:18.52428Z","caller":"traceutil/trace.go:171","msg":"trace[73854236] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1993; }","duration":"504.540931ms","start":"2026-04-01T15:29:18.019711Z","end":"2026-04-01T15:29:18.524252Z","steps":["trace[73854236] 'agreement among raft nodes before linearized reading' (duration: 504.368747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.52429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.566176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:29:18.524283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.009226Z","time spent":"515.046885ms","remote":"127.0.0.1:39228","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":"info","ts":"2026-04-01T15:29:18.524294Z","caller":"traceutil/trace.go:171","msg":"trace[1970584132] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1993; }","duration":"215.505549ms","start":"2026-04-01T15:29:18.308775Z","end":"2026-04-01T15:29:18.52428Z","steps":["trace[1970584132] 'agreement among raft nodes before linearized reading' (duration: 215.377436ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:18.524319Z","caller":"traceutil/trace.go:171","msg":"trace[136872348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1993; }","duration":"306.626846ms","start":"2026-04-01T15:29:18.217685Z","end":"2026-04-01T15:29:18.524312Z","steps":["trace[136872348] 'agreement among raft nodes before linearized reading' (duration: 306.586735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.524329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.0197Z","time spent":"504.621582ms","remote":"127.0.0.1:56544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T15:29:18.524338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.217667Z","time spent":"306.667498ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:29:18.524333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.181758Z","time spent":"342.556336ms","remote":"127.0.0.1:48644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:29:18.524265Z","caller":"traceutil/trace.go:171","msg":"trace[1367490617] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"288.893507ms","start":"2026-04-01T15:29:18.235295Z","end":"2026-04-01T15:29:18.524189Z","steps":["trace[1367490617] 'process raft request' (duration: 288.57219ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:18.524264Z","caller":"traceutil/trace.go:171","msg":"trace[1494726321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1993; }","duration":"504.54024ms","start":"2026-04-01T15:29:18.019647Z","end":"2026-04-01T15:29:18.524187Z","steps":["trace[1494726321] 'agreement among raft nodes before linearized reading' (duration: 504.376306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.524588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.019637Z","time spent":"504.934579ms","remote":"127.0.0.1:39228","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-04-01T15:29:18.524835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.521806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:29:18.524879Z","caller":"traceutil/trace.go:171","msg":"trace[1195483189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1993; }","duration":"515.565757ms","start":"2026-04-01T15:29:18.009302Z","end":"2026-04-01T15:29:18.524868Z","steps":["trace[1195483189] 'agreement among raft nodes before linearized reading' (duration: 515.447565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:18.524907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:18.009244Z","time spent":"515.65613ms","remote":"127.0.0.1:39228","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":"warn","ts":"2026-04-01T15:29:50.413094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.053634ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T15:29:50.798106Z","caller":"traceutil/trace.go:171","msg":"trace[1914200051] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"113.342665ms","start":"2026-04-01T15:29:50.684739Z","end":"2026-04-01T15:29:50.798081Z","steps":["trace[1914200051] 'process raft request' (duration: 113.202522ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:50.891161Z","caller":"traceutil/trace.go:171","msg":"trace[1255289034] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"159.640385ms","start":"2026-04-01T15:29:50.731491Z","end":"2026-04-01T15:29:50.891132Z","steps":["trace[1255289034] 'process raft request' (duration: 94.449687ms)","trace[1255289034] 'compare' (duration: 64.931182ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:50.891262Z","caller":"traceutil/trace.go:171","msg":"trace[1628717591] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2248; }","duration":"127.940508ms","start":"2026-04-01T15:29:50.763305Z","end":"2026-04-01T15:29:50.891245Z","steps":["trace[1628717591] 'read index received' (duration: 34.68202ms)","trace[1628717591] 'applied index is now lower than readState.Index' (duration: 93.257358ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:50.891339Z","caller":"traceutil/trace.go:171","msg":"trace[1254210898] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"149.899539ms","start":"2026-04-01T15:29:50.74143Z","end":"2026-04-01T15:29:50.891329Z","steps":["trace[1254210898] 'process raft request' (duration: 149.674113ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:50.891166Z","caller":"traceutil/trace.go:171","msg":"trace[366334717] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"153.370684ms","start":"2026-04-01T15:29:50.737771Z","end":"2026-04-01T15:29:50.891142Z","steps":["trace[366334717] 'process raft request' (duration: 153.286492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:50.891474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.160464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-01T15:29:50.891499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.101696ms","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-04-01T15:29:50.891511Z","caller":"traceutil/trace.go:171","msg":"trace[930860851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"128.250456ms","start":"2026-04-01T15:29:50.763251Z","end":"2026-04-01T15:29:50.891502Z","steps":["trace[930860851] 'agreement among raft nodes before linearized reading' (duration: 128.091262ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:50.891545Z","caller":"traceutil/trace.go:171","msg":"trace[2086636124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2169; }","duration":"122.169868ms","start":"2026-04-01T15:29:50.769363Z","end":"2026-04-01T15:29:50.891533Z","steps":["trace[2086636124] 'agreement among raft nodes before linearized reading' (duration: 122.043805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:50.891551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.158242ms","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-04-01T15:29:50.891587Z","caller":"traceutil/trace.go:171","msg":"trace[1531950132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2169; }","duration":"123.218644ms","start":"2026-04-01T15:29:50.76836Z","end":"2026-04-01T15:29:50.891578Z","steps":["trace[1531950132] 'agreement among raft nodes before linearized reading' (duration: 123.118421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:50.891708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.44349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-01T15:29:50.891779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.252158ms","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-04-01T15:29:50.891776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.536322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:29:50.891806Z","caller":"traceutil/trace.go:171","msg":"trace[1996911413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"109.565514ms","start":"2026-04-01T15:29:50.782213Z","end":"2026-04-01T15:29:50.891779Z","steps":["trace[1996911413] 'agreement among raft nodes before linearized reading' (duration: 109.341968ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:50.891819Z","caller":"traceutil/trace.go:171","msg":"trace[1220099527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2169; }","duration":"124.33025ms","start":"2026-04-01T15:29:50.767477Z","end":"2026-04-01T15:29:50.891807Z","steps":["trace[1220099527] 'agreement among raft nodes before linearized reading' (duration: 123.88504ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:50.891857Z","caller":"traceutil/trace.go:171","msg":"trace[1255062205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2169; }","duration":"123.669314ms","start":"2026-04-01T15:29:50.768168Z","end":"2026-04-01T15:29:50.891837Z","steps":["trace[1255062205] 'agreement among raft nodes before linearized reading' (duration: 123.448899ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:51.09643Z","caller":"traceutil/trace.go:171","msg":"trace[755057409] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"200.148205ms","start":"2026-04-01T15:29:50.896234Z","end":"2026-04-01T15:29:51.096382Z","steps":["trace[755057409] 'process raft request' (duration: 124.458733ms)","trace[755057409] 'compare' (duration: 75.436516ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:51.096653Z","caller":"traceutil/trace.go:171","msg":"trace[374008433] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"200.199427ms","start":"2026-04-01T15:29:50.896427Z","end":"2026-04-01T15:29:51.096626Z","steps":["trace[374008433] 'process raft request' (duration: 199.88762ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:51.096682Z","caller":"traceutil/trace.go:171","msg":"trace[1250980012] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"200.012253ms","start":"2026-04-01T15:29:50.896639Z","end":"2026-04-01T15:29:51.096651Z","steps":["trace[1250980012] 'process raft request' (duration: 199.864959ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:51.096818Z","caller":"traceutil/trace.go:171","msg":"trace[938337907] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"200.120166ms","start":"2026-04-01T15:29:50.896685Z","end":"2026-04-01T15:29:51.096806Z","steps":["trace[938337907] 'process raft request' (duration: 199.92918ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:51.097049Z","caller":"traceutil/trace.go:171","msg":"trace[1814673258] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"200.162517ms","start":"2026-04-01T15:29:50.896792Z","end":"2026-04-01T15:29:51.096954Z","steps":["trace[1814673258] 'process raft request' (duration: 199.947532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:57.429891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842447461863852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T15:29:57.618426Z","caller":"traceutil/trace.go:171","msg":"trace[660692956] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"799.365586ms","start":"2026-04-01T15:29:56.819036Z","end":"2026-04-01T15:29:57.618402Z","steps":["trace[660692956] 'process raft request' (duration: 799.208732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:57.618593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.819006Z","time spent":"799.49787ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:58.285325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.181179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:29:58.285747Z","caller":"traceutil/trace.go:171","msg":"trace[732553555] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2281; }","duration":"1.356149618s","start":"2026-04-01T15:29:56.929577Z","end":"2026-04-01T15:29:58.285727Z","steps":["trace[732553555] 'read index received' (duration: 688.806309ms)","trace[732553555] 'applied index is now lower than readState.Index' (duration: 667.342109ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:58.285855Z","caller":"traceutil/trace.go:171","msg":"trace[280031403] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"1.374594795s","start":"2026-04-01T15:29:56.911227Z","end":"2026-04-01T15:29:58.285822Z","steps":["trace[280031403] 'process raft request' (duration: 1.016531104s)","trace[280031403] 'compare' (duration: 356.877312ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:58.285925Z","caller":"traceutil/trace.go:171","msg":"trace[1335984263] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"678.048849ms","start":"2026-04-01T15:29:57.607838Z","end":"2026-04-01T15:29:58.285887Z","steps":["trace[1335984263] 'process raft request' (duration: 677.849484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.285991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068962854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:58.286037Z","caller":"traceutil/trace.go:171","msg":"trace[1292634074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2202; }","duration":"1.069048436s","start":"2026-04-01T15:29:57.216975Z","end":"2026-04-01T15:29:58.286023Z","steps":["trace[1292634074] 'agreement among raft nodes before linearized reading' (duration: 1.068952533s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.285861Z","caller":"traceutil/trace.go:171","msg":"trace[1025999627] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"1.372799441s","start":"2026-04-01T15:29:56.913035Z","end":"2026-04-01T15:29:58.285834Z","steps":["trace[1025999627] 'process raft request' (duration: 1.372610157s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.286091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.216961Z","time spent":"1.069119798s","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264746716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a2454549321751\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-01T15:29:58.286146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.607812Z","time spent":"678.212663ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:58.286175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168595016s","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-04-01T15:29:58.286159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353716248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T15:29:58.28619Z","caller":"traceutil/trace.go:171","msg":"trace[1048959556] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a2454549321751; range_end:; response_count:1; response_revision:2202; }","duration":"1.264880148s","start":"2026-04-01T15:29:57.021282Z","end":"2026-04-01T15:29:58.286162Z","steps":["trace[1048959556] 'agreement among raft nodes before linearized reading' (duration: 1.264668773s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.286218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.913007Z","time spent":"1.373131048s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:29:58.286237Z","caller":"traceutil/trace.go:171","msg":"trace[1448363164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2202; }","duration":"1.168684588s","start":"2026-04-01T15:29:57.117535Z","end":"2026-04-01T15:29:58.286219Z","steps":["trace[1448363164] 'agreement among raft nodes before linearized reading' (duration: 1.168578736s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.286249Z","caller":"traceutil/trace.go:171","msg":"trace[1354025225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2202; }","duration":"1.353851272s","start":"2026-04-01T15:29:56.93238Z","end":"2026-04-01T15:29:58.286231Z","steps":["trace[1354025225] 'agreement among raft nodes before linearized reading' (duration: 1.353514664s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.28626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.021264Z","time spent":"1.264981921s","remote":"127.0.0.1:39046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a2454549321751\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356590729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-01T15:29:58.28629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.117514Z","time spent":"1.168765751s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.93236Z","time spent":"1.353923994s","remote":"127.0.0.1:39228","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-04-01T15:29:58.286345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167999122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-01T15:29:58.286066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170224976s","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-04-01T15:29:58.286371Z","caller":"traceutil/trace.go:171","msg":"trace[1620389449] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2202; }","duration":"1.356796783s","start":"2026-04-01T15:29:56.929522Z","end":"2026-04-01T15:29:58.286319Z","steps":["trace[1620389449] 'agreement among raft nodes before linearized reading' (duration: 1.356348312s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.28641Z","caller":"traceutil/trace.go:171","msg":"trace[734305273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2202; }","duration":"1.170622756s","start":"2026-04-01T15:29:57.115777Z","end":"2026-04-01T15:29:58.2864Z","steps":["trace[734305273] 'agreement among raft nodes before linearized reading' (duration: 1.170123694s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.286412Z","caller":"traceutil/trace.go:171","msg":"trace[1305358775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2202; }","duration":"1.168080044s","start":"2026-04-01T15:29:57.118316Z","end":"2026-04-01T15:29:58.286396Z","steps":["trace[1305358775] 'agreement among raft nodes before linearized reading' (duration: 1.167900589s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.286448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.929505Z","time spent":"1.356926617s","remote":"127.0.0.1:56544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.115757Z","time spent":"1.170686967s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-01T15:29:58.285852Z","caller":"traceutil/trace.go:171","msg":"trace[1454095573] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"1.374196996s","start":"2026-04-01T15:29:56.911606Z","end":"2026-04-01T15:29:58.285803Z","steps":["trace[1454095573] 'process raft request' (duration: 1.373935799s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.286467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.11831Z","time spent":"1.168143836s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.9112Z","time spent":"1.374737879s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:58.286525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:56.911595Z","time spent":"1.374899172s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:29:58.286518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168256228s","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-04-01T15:29:58.286686Z","caller":"traceutil/trace.go:171","msg":"trace[1753902791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2202; }","duration":"1.168480654s","start":"2026-04-01T15:29:57.118187Z","end":"2026-04-01T15:29:58.286667Z","steps":["trace[1753902791] 'agreement among raft nodes before linearized reading' (duration: 1.167706186s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.286779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.118175Z","time spent":"1.168588836s","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T15:29:58.286687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.964831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12381"} {"level":"info","ts":"2026-04-01T15:29:58.286919Z","caller":"traceutil/trace.go:171","msg":"trace[918163208] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2202; }","duration":"912.216617ms","start":"2026-04-01T15:29:57.374672Z","end":"2026-04-01T15:29:58.286889Z","steps":["trace[918163208] 'agreement among raft nodes before linearized reading' (duration: 911.346037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:29:57.374652Z","time spent":"912.31491ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12403,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-01T15:29:58.461143Z","caller":"traceutil/trace.go:171","msg":"trace[1704758576] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"170.101687ms","start":"2026-04-01T15:29:58.291006Z","end":"2026-04-01T15:29:58.461108Z","steps":["trace[1704758576] 'process raft request' (duration: 167.557756ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.46117Z","caller":"traceutil/trace.go:171","msg":"trace[1149164011] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"168.053389ms","start":"2026-04-01T15:29:58.293088Z","end":"2026-04-01T15:29:58.461141Z","steps":["trace[1149164011] 'process raft request' (duration: 167.952717ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.461404Z","caller":"traceutil/trace.go:171","msg":"trace[588850059] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"168.300495ms","start":"2026-04-01T15:29:58.293087Z","end":"2026-04-01T15:29:58.461388Z","steps":["trace[588850059] 'process raft request' (duration: 168.008088ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.461486Z","caller":"traceutil/trace.go:171","msg":"trace[1848177235] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"168.168272ms","start":"2026-04-01T15:29:58.29324Z","end":"2026-04-01T15:29:58.461408Z","steps":["trace[1848177235] 'process raft request' (duration: 167.981447ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.461546Z","caller":"traceutil/trace.go:171","msg":"trace[473155271] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"168.504661ms","start":"2026-04-01T15:29:58.293016Z","end":"2026-04-01T15:29:58.461521Z","steps":["trace[473155271] 'process raft request' (duration: 167.940347ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:29:58.461564Z","caller":"traceutil/trace.go:171","msg":"trace[1714164958] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2286; }","duration":"165.478508ms","start":"2026-04-01T15:29:58.296056Z","end":"2026-04-01T15:29:58.461535Z","steps":["trace[1714164958] 'read index received' (duration: 56.066628ms)","trace[1714164958] 'applied index is now lower than readState.Index' (duration: 109.40956ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:29:58.4615Z","caller":"traceutil/trace.go:171","msg":"trace[523603862] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"108.21106ms","start":"2026-04-01T15:29:58.353245Z","end":"2026-04-01T15:29:58.461456Z","steps":["trace[523603862] 'process raft request' (duration: 108.078227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.461713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.634511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:58.461895Z","caller":"traceutil/trace.go:171","msg":"trace[1223752539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2208; }","duration":"165.832806ms","start":"2026-04-01T15:29:58.296041Z","end":"2026-04-01T15:29:58.461874Z","steps":["trace[1223752539] 'agreement among raft nodes before linearized reading' (duration: 165.61287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:29:58.461753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.671229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:29:58.461975Z","caller":"traceutil/trace.go:171","msg":"trace[579379021] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2208; }","duration":"162.919345ms","start":"2026-04-01T15:29:58.299042Z","end":"2026-04-01T15:29:58.461962Z","steps":["trace[579379021] 'agreement among raft nodes before linearized reading' (duration: 162.647958ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:30:39.3021Z","caller":"traceutil/trace.go:171","msg":"trace[1521000234] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"318.073461ms","start":"2026-04-01T15:30:38.983999Z","end":"2026-04-01T15:30:39.302073Z","steps":["trace[1521000234] 'process raft request' (duration: 317.798415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:30:39.302352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:30:38.98397Z","time spent":"318.239565ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:31:04.135384Z","caller":"traceutil/trace.go:171","msg":"trace[1763474226] linearizableReadLoop","detail":"{readStateIndex:2860; appliedIndex:2859; }","duration":"349.734015ms","start":"2026-04-01T15:31:03.785624Z","end":"2026-04-01T15:31:04.135358Z","steps":["trace[1763474226] 'read index received' (duration: 349.578081ms)","trace[1763474226] 'applied index is now lower than readState.Index' (duration: 154.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:31:04.135562Z","caller":"traceutil/trace.go:171","msg":"trace[1763766742] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"816.264976ms","start":"2026-04-01T15:31:03.319216Z","end":"2026-04-01T15:31:04.135481Z","steps":["trace[1763766742] 'process raft request' (duration: 815.988629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:04.135793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:03.319189Z","time spent":"816.491632ms","remote":"127.0.0.1:39046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:31:04.135624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.987161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-01T15:31:04.135941Z","caller":"traceutil/trace.go:171","msg":"trace[516957221] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2753; }","duration":"350.3512ms","start":"2026-04-01T15:31:03.785545Z","end":"2026-04-01T15:31:04.135896Z","steps":["trace[516957221] 'agreement among raft nodes before linearized reading' (duration: 349.921089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:04.136453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:03.785523Z","time spent":"350.906633ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-04-01T15:31:04.136291Z","caller":"traceutil/trace.go:171","msg":"trace[580199939] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"217.883472ms","start":"2026-04-01T15:31:03.918378Z","end":"2026-04-01T15:31:04.136261Z","steps":["trace[580199939] 'process raft request' (duration: 217.724898ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:12.755696Z","caller":"traceutil/trace.go:171","msg":"trace[1001427557] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"169.179043ms","start":"2026-04-01T15:31:12.586476Z","end":"2026-04-01T15:31:12.755655Z","steps":["trace[1001427557] 'process raft request' (duration: 144.304337ms)","trace[1001427557] 'compare' (duration: 24.262131ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:31:12.764257Z","caller":"traceutil/trace.go:171","msg":"trace[1045681041] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"176.341158ms","start":"2026-04-01T15:31:12.587893Z","end":"2026-04-01T15:31:12.764234Z","steps":["trace[1045681041] 'process raft request' (duration: 176.277187ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:12.764329Z","caller":"traceutil/trace.go:171","msg":"trace[524151411] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"177.065287ms","start":"2026-04-01T15:31:12.587225Z","end":"2026-04-01T15:31:12.76429Z","steps":["trace[524151411] 'process raft request' (duration: 176.821461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.513683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.038997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:31:13.513787Z","caller":"traceutil/trace.go:171","msg":"trace[1089913238] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2910; }","duration":"636.917156ms","start":"2026-04-01T15:31:12.876857Z","end":"2026-04-01T15:31:13.513774Z","steps":["trace[1089913238] 'read index received' (duration: 157.682844ms)","trace[1089913238] 'applied index is now lower than readState.Index' (duration: 479.233332ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:31:13.514098Z","caller":"traceutil/trace.go:171","msg":"trace[433393220] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"665.037711ms","start":"2026-04-01T15:31:12.849051Z","end":"2026-04-01T15:31:13.514088Z","steps":["trace[433393220] 'process raft request' (duration: 185.500313ms)","trace[433393220] 'compare' (duration: 478.739609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:31:13.514262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:12.84903Z","time spent":"665.103542ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:31:13.514453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.495389ms","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-04-01T15:31:13.514475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.194815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:31:13.514545Z","caller":"traceutil/trace.go:171","msg":"trace[345773559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2802; }","duration":"637.628403ms","start":"2026-04-01T15:31:12.876896Z","end":"2026-04-01T15:31:13.514524Z","steps":["trace[345773559] 'agreement among raft nodes before linearized reading' (duration: 637.398827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.51448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.608857ms","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-04-01T15:31:13.514615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:12.876877Z","time spent":"637.725406ms","remote":"127.0.0.1:39228","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":"info","ts":"2026-04-01T15:31:13.514669Z","caller":"traceutil/trace.go:171","msg":"trace[292011226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2802; }","duration":"636.818653ms","start":"2026-04-01T15:31:12.877834Z","end":"2026-04-01T15:31:13.514653Z","steps":["trace[292011226] 'agreement among raft nodes before linearized reading' (duration: 636.547346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.514651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.199022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-01T15:31:13.514712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:12.877824Z","time spent":"636.878194ms","remote":"127.0.0.1:39228","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-04-01T15:31:13.514728Z","caller":"traceutil/trace.go:171","msg":"trace[1590812390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2802; }","duration":"637.310564ms","start":"2026-04-01T15:31:12.877399Z","end":"2026-04-01T15:31:13.514709Z","steps":["trace[1590812390] 'agreement among raft nodes before linearized reading' (duration: 636.990046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.514479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.568061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T15:31:13.514772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:12.877388Z","time spent":"637.376445ms","remote":"127.0.0.1:39228","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-04-01T15:31:13.514993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.715678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a2455ba8179fda\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-01T15:31:13.515113Z","caller":"traceutil/trace.go:171","msg":"trace[318152102] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2802; }","duration":"462.343649ms","start":"2026-04-01T15:31:13.052197Z","end":"2026-04-01T15:31:13.514541Z","steps":["trace[318152102] 'agreement among raft nodes before linearized reading' (duration: 462.153715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.51518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:13.051496Z","time spent":"463.671131ms","remote":"127.0.0.1:35830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:31:13.515116Z","caller":"traceutil/trace.go:171","msg":"trace[334519436] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a2455ba8179fda; range_end:; response_count:1; response_revision:2802; }","duration":"215.907012ms","start":"2026-04-01T15:31:13.299184Z","end":"2026-04-01T15:31:13.515091Z","steps":["trace[334519436] 'agreement among raft nodes before linearized reading' (duration: 215.202415ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:13.515121Z","caller":"traceutil/trace.go:171","msg":"trace[533276307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2802; }","duration":"637.947641ms","start":"2026-04-01T15:31:12.876819Z","end":"2026-04-01T15:31:13.514767Z","steps":["trace[533276307] 'agreement among raft nodes before linearized reading' (duration: 637.449759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:31:13.515637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:31:12.876801Z","time spent":"638.827411ms","remote":"127.0.0.1:39228","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-04-01T15:31:13.708711Z","caller":"traceutil/trace.go:171","msg":"trace[61053971] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"190.863852ms","start":"2026-04-01T15:31:13.51782Z","end":"2026-04-01T15:31:13.708684Z","steps":["trace[61053971] 'process raft request' (duration: 175.518678ms)","trace[61053971] 'compare' (duration: 14.899433ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:31:13.708939Z","caller":"traceutil/trace.go:171","msg":"trace[167975031] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"189.772625ms","start":"2026-04-01T15:31:13.519148Z","end":"2026-04-01T15:31:13.708921Z","steps":["trace[167975031] 'process raft request' (duration: 189.477628ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:13.709217Z","caller":"traceutil/trace.go:171","msg":"trace[85271001] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"185.583383ms","start":"2026-04-01T15:31:13.523618Z","end":"2026-04-01T15:31:13.709201Z","steps":["trace[85271001] 'process raft request' (duration: 185.512082ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:13.709252Z","caller":"traceutil/trace.go:171","msg":"trace[472547505] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"186.919135ms","start":"2026-04-01T15:31:13.522312Z","end":"2026-04-01T15:31:13.709231Z","steps":["trace[472547505] 'process raft request' (duration: 186.541366ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:13.709337Z","caller":"traceutil/trace.go:171","msg":"trace[2127024494] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"186.819094ms","start":"2026-04-01T15:31:13.522481Z","end":"2026-04-01T15:31:13.7093Z","steps":["trace[2127024494] 'process raft request' (duration: 186.599888ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:24.960254Z","caller":"traceutil/trace.go:171","msg":"trace[1357257883] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"141.460008ms","start":"2026-04-01T15:31:24.818771Z","end":"2026-04-01T15:31:24.960231Z","steps":["trace[1357257883] 'process raft request' (duration: 141.402617ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:24.960604Z","caller":"traceutil/trace.go:171","msg":"trace[1116572018] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"147.323562ms","start":"2026-04-01T15:31:24.813269Z","end":"2026-04-01T15:31:24.960593Z","steps":["trace[1116572018] 'process raft request' (duration: 120.154769ms)","trace[1116572018] 'compare' (duration: 26.322343ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:31:25.910206Z","caller":"traceutil/trace.go:171","msg":"trace[1977388209] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"127.442827ms","start":"2026-04-01T15:31:25.78274Z","end":"2026-04-01T15:31:25.910183Z","steps":["trace[1977388209] 'process raft request' (duration: 127.402966ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:31:25.910249Z","caller":"traceutil/trace.go:171","msg":"trace[1964807076] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"128.555604ms","start":"2026-04-01T15:31:25.781675Z","end":"2026-04-01T15:31:25.91023Z","steps":["trace[1964807076] 'process raft request' (duration: 125.172692ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:18.064789Z","caller":"traceutil/trace.go:171","msg":"trace[1969252814] transaction","detail":"{read_only:false; response_revision:3581; number_of_response:1; }","duration":"235.460947ms","start":"2026-04-01T15:33:17.829305Z","end":"2026-04-01T15:33:18.064766Z","steps":["trace[1969252814] 'process raft request' (duration: 235.323203ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:44.770491Z","caller":"traceutil/trace.go:171","msg":"trace[1345399486] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"156.905512ms","start":"2026-04-01T15:33:44.613557Z","end":"2026-04-01T15:33:44.770462Z","steps":["trace[1345399486] 'process raft request' (duration: 156.732518ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:44.816579Z","caller":"traceutil/trace.go:171","msg":"trace[1874361228] linearizableReadLoop","detail":"{readStateIndex:3945; appliedIndex:3943; }","duration":"200.435459ms","start":"2026-04-01T15:33:44.616126Z","end":"2026-04-01T15:33:44.816562Z","steps":["trace[1874361228] 'read index received' (duration: 154.128174ms)","trace[1874361228] 'applied index is now lower than readState.Index' (duration: 46.306175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:33:44.816864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.716085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T15:33:44.81695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.375602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-01T15:33:44.817011Z","caller":"traceutil/trace.go:171","msg":"trace[1960726404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3800; }","duration":"172.468524ms","start":"2026-04-01T15:33:44.64453Z","end":"2026-04-01T15:33:44.816999Z","steps":["trace[1960726404] 'agreement among raft nodes before linearized reading' (duration: 172.28745ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:44.816941Z","caller":"traceutil/trace.go:171","msg":"trace[1547639611] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3800; }","duration":"200.839219ms","start":"2026-04-01T15:33:44.616089Z","end":"2026-04-01T15:33:44.816929Z","steps":["trace[1547639611] 'agreement among raft nodes before linearized reading' (duration: 200.566042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:33:44.817042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.341022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-01T15:33:44.817134Z","caller":"traceutil/trace.go:171","msg":"trace[819913363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3800; }","duration":"168.474085ms","start":"2026-04-01T15:33:44.648641Z","end":"2026-04-01T15:33:44.817115Z","steps":["trace[819913363] 'agreement among raft nodes before linearized reading' (duration: 168.194588ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:44.816689Z","caller":"traceutil/trace.go:171","msg":"trace[383071482] transaction","detail":"{read_only:false; response_revision:3800; number_of_response:1; }","duration":"201.895524ms","start":"2026-04-01T15:33:44.614735Z","end":"2026-04-01T15:33:44.81663Z","steps":["trace[383071482] 'process raft request' (duration: 199.615639ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:33:50.509089Z","caller":"traceutil/trace.go:171","msg":"trace[478144132] linearizableReadLoop","detail":"{readStateIndex:3973; appliedIndex:3972; }","duration":"140.91085ms","start":"2026-04-01T15:33:50.368151Z","end":"2026-04-01T15:33:50.509062Z","steps":["trace[478144132] 'read index received' (duration: 42.602213ms)","trace[478144132] 'applied index is now lower than readState.Index' (duration: 98.306897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:33:50.509359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.189657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T15:33:50.50942Z","caller":"traceutil/trace.go:171","msg":"trace[1118646180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3826; }","duration":"141.296ms","start":"2026-04-01T15:33:50.368113Z","end":"2026-04-01T15:33:50.509409Z","steps":["trace[1118646180] 'agreement among raft nodes before linearized reading' (duration: 141.094845ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:06.641058Z","caller":"traceutil/trace.go:171","msg":"trace[173329726] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"113.101759ms","start":"2026-04-01T15:35:06.527926Z","end":"2026-04-01T15:35:06.641027Z","steps":["trace[173329726] 'process raft request' (duration: 111.890079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:08.030805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.337796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-76rqc\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-04-01T15:35:08.031Z","caller":"traceutil/trace.go:171","msg":"trace[2058387619] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-76rqc; range_end:; response_count:1; response_revision:4775; }","duration":"104.571912ms","start":"2026-04-01T15:35:07.926375Z","end":"2026-04-01T15:35:08.030947Z","steps":["trace[2058387619] 'range keys from in-memory index tree' (duration: 104.08321ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:13.381966Z","caller":"traceutil/trace.go:171","msg":"trace[1986618088] linearizableReadLoop","detail":"{readStateIndex:5081; appliedIndex:5080; }","duration":"144.065151ms","start":"2026-04-01T15:35:13.237872Z","end":"2026-04-01T15:35:13.381937Z","steps":["trace[1986618088] 'read index received' (duration: 107.427431ms)","trace[1986618088] 'applied index is now lower than readState.Index' (duration: 36.63657ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:13.381975Z","caller":"traceutil/trace.go:171","msg":"trace[743141384] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"145.367392ms","start":"2026-04-01T15:35:13.23657Z","end":"2026-04-01T15:35:13.381937Z","steps":["trace[743141384] 'process raft request' (duration: 108.718352ms)","trace[743141384] 'compare' (duration: 36.506356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:13.382137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.248045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-01T15:35:13.382184Z","caller":"traceutil/trace.go:171","msg":"trace[1521287157] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4891; }","duration":"144.324567ms","start":"2026-04-01T15:35:13.237848Z","end":"2026-04-01T15:35:13.382173Z","steps":["trace[1521287157] 'agreement among raft nodes before linearized reading' (duration: 144.189034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:13.382449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.502044ms","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-04-01T15:35:13.382509Z","caller":"traceutil/trace.go:171","msg":"trace[1647818823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4891; }","duration":"119.602996ms","start":"2026-04-01T15:35:13.262889Z","end":"2026-04-01T15:35:13.382492Z","steps":["trace[1647818823] 'agreement among raft nodes before linearized reading' (duration: 119.455693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:13.382462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.741849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T15:35:13.382585Z","caller":"traceutil/trace.go:171","msg":"trace[501313186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4891; }","duration":"119.882413ms","start":"2026-04-01T15:35:13.262695Z","end":"2026-04-01T15:35:13.382577Z","steps":["trace[501313186] 'agreement among raft nodes before linearized reading' (duration: 119.716389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:13.382495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.654811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T15:35:13.382891Z","caller":"traceutil/trace.go:171","msg":"trace[962531357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4891; }","duration":"121.106402ms","start":"2026-04-01T15:35:13.261759Z","end":"2026-04-01T15:35:13.382865Z","steps":["trace[962531357] 'agreement among raft nodes before linearized reading' (duration: 120.514778ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:13.650575Z","caller":"traceutil/trace.go:171","msg":"trace[488963293] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"180.65223ms","start":"2026-04-01T15:35:13.46988Z","end":"2026-04-01T15:35:13.650532Z","steps":["trace[488963293] 'process raft request' (duration: 180.399734ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:13.650442Z","caller":"traceutil/trace.go:171","msg":"trace[1925075920] linearizableReadLoop","detail":"{readStateIndex:5085; appliedIndex:5084; }","duration":"177.086384ms","start":"2026-04-01T15:35:13.473338Z","end":"2026-04-01T15:35:13.650424Z","steps":["trace[1925075920] 'read index received' (duration: 176.91697ms)","trace[1925075920] 'applied index is now lower than readState.Index' (duration: 168.604µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:13.651355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.964695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:521593"} {"level":"info","ts":"2026-04-01T15:35:13.652309Z","caller":"traceutil/trace.go:171","msg":"trace[1751690110] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4895; }","duration":"179.00441ms","start":"2026-04-01T15:35:13.473292Z","end":"2026-04-01T15:35:13.652296Z","steps":["trace[1751690110] 'agreement among raft nodes before linearized reading' (duration: 177.34821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.452344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842447461870522,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T15:35:17.590206Z","caller":"traceutil/trace.go:171","msg":"trace[1788681066] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"643.382204ms","start":"2026-04-01T15:35:16.946799Z","end":"2026-04-01T15:35:17.590181Z","steps":["trace[1788681066] 'process raft request' (duration: 643.22355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.590401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:16.946781Z","time spent":"643.521258ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:35:17.841852Z","caller":"traceutil/trace.go:171","msg":"trace[309492750] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5215; }","duration":"890.048378ms","start":"2026-04-01T15:35:16.951778Z","end":"2026-04-01T15:35:17.841827Z","steps":["trace[309492750] 'read index received' (duration: 638.234679ms)","trace[309492750] 'applied index is now lower than readState.Index' (duration: 251.812369ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:17.841884Z","caller":"traceutil/trace.go:171","msg":"trace[366837073] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"658.001329ms","start":"2026-04-01T15:35:17.18386Z","end":"2026-04-01T15:35:17.841861Z","steps":["trace[366837073] 'process raft request' (duration: 657.779404ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:17.841969Z","caller":"traceutil/trace.go:171","msg":"trace[1966271752] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"614.04068ms","start":"2026-04-01T15:35:17.227888Z","end":"2026-04-01T15:35:17.841929Z","steps":["trace[1966271752] 'process raft request' (duration: 613.888046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.84202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.183827Z","time spent":"658.106432ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:35:17.842092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.302974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-747zv\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-01T15:35:17.842136Z","caller":"traceutil/trace.go:171","msg":"trace[1175990285] range","detail":"{range_begin:/registry/pods/openstack/keepalived-747zv; range_end:; response_count:1; response_revision:5024; }","duration":"890.396346ms","start":"2026-04-01T15:35:16.951728Z","end":"2026-04-01T15:35:17.842124Z","steps":["trace[1175990285] 'agreement among raft nodes before linearized reading' (duration: 890.220891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.8422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:16.951706Z","time spent":"890.486378ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-747zv\" "} {"level":"warn","ts":"2026-04-01T15:35:17.842224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.227857Z","time spent":"614.205634ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:35:17.852871Z","caller":"traceutil/trace.go:171","msg":"trace[494502961] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"102.189704ms","start":"2026-04-01T15:35:17.750656Z","end":"2026-04-01T15:35:17.852845Z","steps":["trace[494502961] 'process raft request' (duration: 101.812375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.853188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.323212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5fk69-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-04-01T15:35:17.853234Z","caller":"traceutil/trace.go:171","msg":"trace[710424737] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5fk69-tls; range_end:; response_count:1; response_revision:5025; }","duration":"173.398733ms","start":"2026-04-01T15:35:17.679823Z","end":"2026-04-01T15:35:17.853221Z","steps":["trace[710424737] 'agreement among raft nodes before linearized reading' (duration: 173.243109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.853415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.75309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:35:17.853449Z","caller":"traceutil/trace.go:171","msg":"trace[1818021371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5025; }","duration":"633.82186ms","start":"2026-04-01T15:35:17.219618Z","end":"2026-04-01T15:35:17.85344Z","steps":["trace[1818021371] 'agreement among raft nodes before linearized reading' (duration: 633.769219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.853514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.219597Z","time spent":"633.908913ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:35:17.853633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.615582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e1675d66-9c30-4bd7-b76f-d89a1faf0937\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-01T15:35:17.853692Z","caller":"traceutil/trace.go:171","msg":"trace[914109183] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e1675d66-9c30-4bd7-b76f-d89a1faf0937; range_end:; response_count:1; response_revision:5025; }","duration":"176.709664ms","start":"2026-04-01T15:35:17.676967Z","end":"2026-04-01T15:35:17.853677Z","steps":["trace[914109183] 'agreement among raft nodes before linearized reading' (duration: 176.573131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.705395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-01T15:35:17.854074Z","caller":"traceutil/trace.go:171","msg":"trace[1250733959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5025; }","duration":"409.756646ms","start":"2026-04-01T15:35:17.444292Z","end":"2026-04-01T15:35:17.854049Z","steps":["trace[1250733959] 'agreement among raft nodes before linearized reading' (duration: 409.622773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.444213Z","time spent":"409.897229ms","remote":"127.0.0.1:39228","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-04-01T15:35:17.854228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.722107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e1675d66-9c30-4bd7-b76f-d89a1faf0937\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-01T15:35:17.854274Z","caller":"traceutil/trace.go:171","msg":"trace[350632221] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e1675d66-9c30-4bd7-b76f-d89a1faf0937; range_end:; response_count:1; response_revision:5025; }","duration":"800.83736ms","start":"2026-04-01T15:35:17.053426Z","end":"2026-04-01T15:35:17.854263Z","steps":["trace[350632221] 'agreement among raft nodes before linearized reading' (duration: 800.675696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.85431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.053398Z","time spent":"800.904841ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e1675d66-9c30-4bd7-b76f-d89a1faf0937\" "} {"level":"warn","ts":"2026-04-01T15:35:17.85437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.231427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-01T15:35:17.854423Z","caller":"traceutil/trace.go:171","msg":"trace[1551054096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5025; }","duration":"410.3016ms","start":"2026-04-01T15:35:17.444111Z","end":"2026-04-01T15:35:17.854413Z","steps":["trace[1551054096] 'agreement among raft nodes before linearized reading' (duration: 410.186717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.444103Z","time spent":"410.34431ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T15:35:17.854608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.304885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T15:35:17.854646Z","caller":"traceutil/trace.go:171","msg":"trace[1023511291] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5025; }","duration":"430.368517ms","start":"2026-04-01T15:35:17.424269Z","end":"2026-04-01T15:35:17.854637Z","steps":["trace[1023511291] 'agreement among raft nodes before linearized reading' (duration: 430.304475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.424259Z","time spent":"430.409068ms","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:35:17.853458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.550353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-01T15:35:17.85476Z","caller":"traceutil/trace.go:171","msg":"trace[746147002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5025; }","duration":"457.895486ms","start":"2026-04-01T15:35:17.396849Z","end":"2026-04-01T15:35:17.854745Z","steps":["trace[746147002] 'agreement among raft nodes before linearized reading' (duration: 456.448541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.396826Z","time spent":"457.965278ms","remote":"127.0.0.1:39228","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-04-01T15:35:17.854914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.921057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-01T15:35:17.854957Z","caller":"traceutil/trace.go:171","msg":"trace[1691881682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5025; }","duration":"441.964629ms","start":"2026-04-01T15:35:17.412982Z","end":"2026-04-01T15:35:17.854947Z","steps":["trace[1691881682] 'agreement among raft nodes before linearized reading' (duration: 441.854506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.854991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.412919Z","time spent":"442.065862ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:35:17.854077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.741056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719252"} {"level":"info","ts":"2026-04-01T15:35:17.855177Z","caller":"traceutil/trace.go:171","msg":"trace[516058535] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5025; }","duration":"708.860814ms","start":"2026-04-01T15:35:17.146297Z","end":"2026-04-01T15:35:17.855158Z","steps":["trace[516058535] 'agreement among raft nodes before linearized reading' (duration: 707.357077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:17.855232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:17.146281Z","time spent":"708.929086ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":719274,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-01T15:35:20.364934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.283557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:35:20.365093Z","caller":"traceutil/trace.go:171","msg":"trace[1912157172] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5268; }","duration":"286.345399ms","start":"2026-04-01T15:35:20.078728Z","end":"2026-04-01T15:35:20.365073Z","steps":["trace[1912157172] 'read index received' (duration: 73.821905ms)","trace[1912157172] 'applied index is now lower than readState.Index' (duration: 212.522134ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:20.365186Z","caller":"traceutil/trace.go:171","msg":"trace[214423729] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"446.355216ms","start":"2026-04-01T15:35:19.91882Z","end":"2026-04-01T15:35:20.365175Z","steps":["trace[214423729] 'process raft request' (duration: 233.73724ms)","trace[214423729] 'compare' (duration: 212.163954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:20.36525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:19.918792Z","time spent":"446.426328ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:35:20.365511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.918511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:35:20.365557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.635658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T15:35:20.365603Z","caller":"traceutil/trace.go:171","msg":"trace[1429673256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5074; }","duration":"147.088415ms","start":"2026-04-01T15:35:20.218492Z","end":"2026-04-01T15:35:20.36558Z","steps":["trace[1429673256] 'agreement among raft nodes before linearized reading' (duration: 146.89624ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:20.365618Z","caller":"traceutil/trace.go:171","msg":"trace[567630872] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5074; }","duration":"117.751591ms","start":"2026-04-01T15:35:20.24785Z","end":"2026-04-01T15:35:20.365602Z","steps":["trace[567630872] 'agreement among raft nodes before linearized reading' (duration: 117.500675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:20.365623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.727021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-01T15:35:20.365721Z","caller":"traceutil/trace.go:171","msg":"trace[1357777659] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5074; }","duration":"173.905616ms","start":"2026-04-01T15:35:20.191791Z","end":"2026-04-01T15:35:20.365697Z","steps":["trace[1357777659] 'agreement among raft nodes before linearized reading' (duration: 173.406214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:20.365919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.186418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777433"} {"level":"info","ts":"2026-04-01T15:35:20.36599Z","caller":"traceutil/trace.go:171","msg":"trace[1269401001] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5074; }","duration":"287.288571ms","start":"2026-04-01T15:35:20.07868Z","end":"2026-04-01T15:35:20.365969Z","steps":["trace[1269401001] 'agreement among raft nodes before linearized reading' (duration: 286.737948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:20.701888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.18122ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T15:35:20.702259Z","caller":"traceutil/trace.go:171","msg":"trace[1268781260] linearizableReadLoop","detail":"{readStateIndex:5273; appliedIndex:5271; }","duration":"207.538153ms","start":"2026-04-01T15:35:20.494688Z","end":"2026-04-01T15:35:20.702226Z","steps":["trace[1268781260] 'read index received' (duration: 6.507198ms)","trace[1268781260] 'applied index is now lower than readState.Index' (duration: 201.028155ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:20.702337Z","caller":"traceutil/trace.go:171","msg":"trace[599793304] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"194.215099ms","start":"2026-04-01T15:35:20.508094Z","end":"2026-04-01T15:35:20.702309Z","steps":["trace[599793304] 'process raft request' (duration: 193.953682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:20.708707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.991889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-04-01T15:35:20.708885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.150436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-01T15:35:20.708886Z","caller":"traceutil/trace.go:171","msg":"trace[1093254587] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5078; }","duration":"214.235076ms","start":"2026-04-01T15:35:20.494625Z","end":"2026-04-01T15:35:20.70886Z","steps":["trace[1093254587] 'agreement among raft nodes before linearized reading' (duration: 207.767339ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:20.708914Z","caller":"traceutil/trace.go:171","msg":"trace[1691427816] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5081; }","duration":"203.220418ms","start":"2026-04-01T15:35:20.505684Z","end":"2026-04-01T15:35:20.708904Z","steps":["trace[1691427816] 'agreement among raft nodes before linearized reading' (duration: 203.092955ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:20.708942Z","caller":"traceutil/trace.go:171","msg":"trace[798084652] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"200.070541ms","start":"2026-04-01T15:35:20.508819Z","end":"2026-04-01T15:35:20.70889Z","steps":["trace[798084652] 'process raft request' (duration: 199.888097ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:20.709112Z","caller":"traceutil/trace.go:171","msg":"trace[768992222] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"199.944028ms","start":"2026-04-01T15:35:20.509161Z","end":"2026-04-01T15:35:20.709105Z","steps":["trace[768992222] 'process raft request' (duration: 199.572699ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:20.709129Z","caller":"traceutil/trace.go:171","msg":"trace[568665431] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"200.767208ms","start":"2026-04-01T15:35:20.508337Z","end":"2026-04-01T15:35:20.709105Z","steps":["trace[568665431] 'process raft request' (duration: 193.958202ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:30.032455Z","caller":"traceutil/trace.go:171","msg":"trace[442098785] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"140.247008ms","start":"2026-04-01T15:35:29.892177Z","end":"2026-04-01T15:35:30.032424Z","steps":["trace[442098785] 'process raft request' (duration: 137.726917ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:30.039606Z","caller":"traceutil/trace.go:171","msg":"trace[424696981] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"137.205204ms","start":"2026-04-01T15:35:29.902376Z","end":"2026-04-01T15:35:30.039581Z","steps":["trace[424696981] 'process raft request' (duration: 136.959318ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:30.039751Z","caller":"traceutil/trace.go:171","msg":"trace[639402705] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5454; }","duration":"119.844693ms","start":"2026-04-01T15:35:29.91988Z","end":"2026-04-01T15:35:30.039725Z","steps":["trace[639402705] 'read index received' (duration: 110.033165ms)","trace[639402705] 'applied index is now lower than readState.Index' (duration: 9.808807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:30.0399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.020606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-747zv\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-01T15:35:30.039934Z","caller":"traceutil/trace.go:171","msg":"trace[911625860] range","detail":"{range_begin:/registry/pods/openstack/keepalived-747zv; range_end:; response_count:1; response_revision:5256; }","duration":"120.127349ms","start":"2026-04-01T15:35:29.919796Z","end":"2026-04-01T15:35:30.039923Z","steps":["trace[911625860] 'agreement among raft nodes before linearized reading' (duration: 120.002006ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:30.549553Z","caller":"traceutil/trace.go:171","msg":"trace[1652187428] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"135.041181ms","start":"2026-04-01T15:35:30.414488Z","end":"2026-04-01T15:35:30.549529Z","steps":["trace[1652187428] 'process raft request' (duration: 134.855517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:30.774502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.025544ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T15:35:30.774625Z","caller":"traceutil/trace.go:171","msg":"trace[453588880] linearizableReadLoop","detail":"{readStateIndex:5470; appliedIndex:5469; }","duration":"276.059057ms","start":"2026-04-01T15:35:30.498546Z","end":"2026-04-01T15:35:30.774605Z","steps":["trace[453588880] 'read index received' (duration: 51.684475ms)","trace[453588880] 'applied index is now lower than readState.Index' (duration: 224.373122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:30.774768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.631442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:35:30.774808Z","caller":"traceutil/trace.go:171","msg":"trace[865454989] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5267; }","duration":"195.725385ms","start":"2026-04-01T15:35:30.579071Z","end":"2026-04-01T15:35:30.774796Z","steps":["trace[865454989] 'agreement among raft nodes before linearized reading' (duration: 195.640133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:30.77487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.306813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-nwm58\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-04-01T15:35:30.774958Z","caller":"traceutil/trace.go:171","msg":"trace[2004973220] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-nwm58; range_end:; response_count:1; response_revision:5267; }","duration":"276.454528ms","start":"2026-04-01T15:35:30.498481Z","end":"2026-04-01T15:35:30.774935Z","steps":["trace[2004973220] 'agreement among raft nodes before linearized reading' (duration: 276.211541ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:30.970064Z","caller":"traceutil/trace.go:171","msg":"trace[1741010655] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"121.747238ms","start":"2026-04-01T15:35:30.848283Z","end":"2026-04-01T15:35:30.97003Z","steps":["trace[1741010655] 'process raft request' (duration: 92.309303ms)","trace[1741010655] 'compare' (duration: 29.294091ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:31.061962Z","caller":"traceutil/trace.go:171","msg":"trace[1063888526] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"212.894523ms","start":"2026-04-01T15:35:30.849021Z","end":"2026-04-01T15:35:31.061916Z","steps":["trace[1063888526] 'process raft request' (duration: 212.632177ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:31.062044Z","caller":"traceutil/trace.go:171","msg":"trace[269311557] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"212.76493ms","start":"2026-04-01T15:35:30.849239Z","end":"2026-04-01T15:35:31.062004Z","steps":["trace[269311557] 'process raft request' (duration: 212.571035ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:31.062003Z","caller":"traceutil/trace.go:171","msg":"trace[1494883347] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"212.578076ms","start":"2026-04-01T15:35:30.849409Z","end":"2026-04-01T15:35:31.061987Z","steps":["trace[1494883347] 'process raft request' (duration: 212.464423ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:31.062229Z","caller":"traceutil/trace.go:171","msg":"trace[1499771538] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"208.493066ms","start":"2026-04-01T15:35:30.853702Z","end":"2026-04-01T15:35:31.062195Z","steps":["trace[1499771538] 'process raft request' (duration: 208.214439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:31.905632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.937135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:35:31.90587Z","caller":"traceutil/trace.go:171","msg":"trace[1584599232] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5477; }","duration":"687.515375ms","start":"2026-04-01T15:35:31.218329Z","end":"2026-04-01T15:35:31.905844Z","steps":["trace[1584599232] 'read index received' (duration: 125.168091ms)","trace[1584599232] 'applied index is now lower than readState.Index' (duration: 562.346114ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:35:31.905988Z","caller":"traceutil/trace.go:171","msg":"trace[935899888] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"835.01153ms","start":"2026-04-01T15:35:31.070966Z","end":"2026-04-01T15:35:31.905977Z","steps":["trace[935899888] 'process raft request' (duration: 834.789865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:31.906069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.070951Z","time spent":"835.066281ms","remote":"127.0.0.1:39424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8123,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:35:31.906343Z","caller":"traceutil/trace.go:171","msg":"trace[775969837] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"835.893261ms","start":"2026-04-01T15:35:31.070408Z","end":"2026-04-01T15:35:31.906302Z","steps":["trace[775969837] 'process raft request' (duration: 273.159168ms)","trace[775969837] 'compare' (duration: 561.77572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:31.906467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.169901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:35:31.906521Z","caller":"traceutil/trace.go:171","msg":"trace[1864963605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5276; }","duration":"688.248323ms","start":"2026-04-01T15:35:31.21825Z","end":"2026-04-01T15:35:31.906498Z","steps":["trace[1864963605] 'agreement among raft nodes before linearized reading' (duration: 688.190542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:31.906562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.218227Z","time spent":"688.327485ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:35:31.906656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.966307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T15:35:31.906711Z","caller":"traceutil/trace.go:171","msg":"trace[1961997247] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5276; }","duration":"479.072381ms","start":"2026-04-01T15:35:31.427622Z","end":"2026-04-01T15:35:31.906694Z","steps":["trace[1961997247] 'agreement among raft nodes before linearized reading' (duration: 478.962457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:31.906376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.386118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-04-01T15:35:31.906727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.070393Z","time spent":"836.197838ms","remote":"127.0.0.1:39138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1329,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:35:31.906758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.427598Z","time spent":"479.151094ms","remote":"127.0.0.1:39290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":8,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:35:31.906768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.456549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T15:35:31.906818Z","caller":"traceutil/trace.go:171","msg":"trace[1822687439] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5276; }","duration":"572.85645ms","start":"2026-04-01T15:35:31.333913Z","end":"2026-04-01T15:35:31.90677Z","steps":["trace[1822687439] 'agreement among raft nodes before linearized reading' (duration: 572.277626ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:31.906834Z","caller":"traceutil/trace.go:171","msg":"trace[25331792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5276; }","duration":"239.562362ms","start":"2026-04-01T15:35:31.667254Z","end":"2026-04-01T15:35:31.906817Z","steps":["trace[25331792] 'agreement among raft nodes before linearized reading' (duration: 239.316985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:31.906877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.333875Z","time spent":"572.984464ms","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-01T15:35:32.234791Z","caller":"traceutil/trace.go:171","msg":"trace[1483703196] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"321.555944ms","start":"2026-04-01T15:35:31.913205Z","end":"2026-04-01T15:35:32.234761Z","steps":["trace[1483703196] 'process raft request' (duration: 316.665105ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.23478Z","caller":"traceutil/trace.go:171","msg":"trace[284568367] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5479; }","duration":"297.306295ms","start":"2026-04-01T15:35:31.937436Z","end":"2026-04-01T15:35:32.234742Z","steps":["trace[284568367] 'read index received' (duration: 292.349774ms)","trace[284568367] 'applied index is now lower than readState.Index' (duration: 4.954711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:35:32.234941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:35:31.913163Z","time spent":"321.705858ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:35:32.234939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.473299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:35:32.235051Z","caller":"traceutil/trace.go:171","msg":"trace[1144078666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5277; }","duration":"297.605752ms","start":"2026-04-01T15:35:31.937418Z","end":"2026-04-01T15:35:32.235024Z","steps":["trace[1144078666] 'agreement among raft nodes before linearized reading' (duration: 297.410278ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.236043Z","caller":"traceutil/trace.go:171","msg":"trace[1895425534] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"197.427347ms","start":"2026-04-01T15:35:32.038562Z","end":"2026-04-01T15:35:32.23599Z","steps":["trace[1895425534] 'process raft request' (duration: 197.057378ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.236062Z","caller":"traceutil/trace.go:171","msg":"trace[1343748428] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"190.343375ms","start":"2026-04-01T15:35:32.045676Z","end":"2026-04-01T15:35:32.236019Z","steps":["trace[1343748428] 'process raft request' (duration: 190.11437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:32.23627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.42755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:35:32.236359Z","caller":"traceutil/trace.go:171","msg":"trace[43756142] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5281; }","duration":"265.552323ms","start":"2026-04-01T15:35:31.970783Z","end":"2026-04-01T15:35:32.236336Z","steps":["trace[43756142] 'agreement among raft nodes before linearized reading' (duration: 265.315287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:32.236394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.145761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-747zv\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-04-01T15:35:32.236404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.713995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-01T15:35:32.236455Z","caller":"traceutil/trace.go:171","msg":"trace[1642936051] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"189.122335ms","start":"2026-04-01T15:35:32.047316Z","end":"2026-04-01T15:35:32.236439Z","steps":["trace[1642936051] 'process raft request' (duration: 188.537281ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.236463Z","caller":"traceutil/trace.go:171","msg":"trace[1031219797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5281; }","duration":"174.777907ms","start":"2026-04-01T15:35:32.061672Z","end":"2026-04-01T15:35:32.23645Z","steps":["trace[1031219797] 'agreement among raft nodes before linearized reading' (duration: 174.619603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:32.236448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.608043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-01T15:35:32.236498Z","caller":"traceutil/trace.go:171","msg":"trace[582844083] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"184.147164ms","start":"2026-04-01T15:35:32.052331Z","end":"2026-04-01T15:35:32.236478Z","steps":["trace[582844083] 'process raft request' (duration: 183.673283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:35:32.236394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.551621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-01T15:35:32.236523Z","caller":"traceutil/trace.go:171","msg":"trace[1749812771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5281; }","duration":"181.725806ms","start":"2026-04-01T15:35:32.054776Z","end":"2026-04-01T15:35:32.236502Z","steps":["trace[1749812771] 'agreement among raft nodes before linearized reading' (duration: 181.415148ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.236545Z","caller":"traceutil/trace.go:171","msg":"trace[51619316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5281; }","duration":"174.762447ms","start":"2026-04-01T15:35:32.061769Z","end":"2026-04-01T15:35:32.236532Z","steps":["trace[51619316] 'agreement among raft nodes before linearized reading' (duration: 174.377867ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:32.236459Z","caller":"traceutil/trace.go:171","msg":"trace[706500349] range","detail":"{range_begin:/registry/pods/openstack/keepalived-747zv; range_end:; response_count:1; response_revision:5281; }","duration":"181.251313ms","start":"2026-04-01T15:35:32.055191Z","end":"2026-04-01T15:35:32.236443Z","steps":["trace[706500349] 'agreement among raft nodes before linearized reading' (duration: 180.967167ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:35:33.981811Z","caller":"traceutil/trace.go:171","msg":"trace[1746171768] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"140.233908ms","start":"2026-04-01T15:35:33.841534Z","end":"2026-04-01T15:35:33.981767Z","steps":["trace[1746171768] 'process raft request' (duration: 139.999472ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:36:51.96374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-04-01T15:36:52.044918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"79.548133ms","hash":1304328264,"current-db-size-bytes":55328768,"current-db-size":"55 MB","current-db-size-in-use-bytes":29822976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-01T15:36:52.044982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1304328264,"revision":3014,"compact-revision":-1}