{"level":"warn","ts":"2026-08-19T08:58:59.45335Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T08:58:59.453444Z","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-08-19T08:58:59.453574Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T08:58:59.453594Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-08-19T08:58:59.45362Z","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-08-19T08:58:59.45474Z","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-08-19T08:58:59.454964Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.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-08-19T08:58:59.461382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.14872ms"} {"level":"info","ts":"2026-08-19T08:58:59.48286Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-08-19T08:58:59.482979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T08:58:59.483016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-08-19T08:58:59.483032Z","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-08-19T08:58:59.48305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-08-19T08:58:59.483103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-08-19T08:58:59.491838Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T08:58:59.494117Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T08:58:59.496895Z","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-08-19T08:58:59.500278Z","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-08-19T08:58:59.502743Z","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-08-19T08:58:59.503072Z","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-08-19T08:58:59.503209Z","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-08-19T08:58:59.50324Z","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-08-19T08:58:59.50705Z","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-08-19T08:58:59.507304Z","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-08-19T08:58:59.507346Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T08:58:59.507419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-08-19T08:58:59.507696Z","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-08-19T08:58:59.507762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-08-19T08:58:59.507808Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-08-19T08:58:59.883313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T08:58:59.883385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T08:58:59.883428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-08-19T08:58:59.883477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-08-19T08:58:59.88349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-08-19T08:58:59.883507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-08-19T08:58:59.88352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-08-19T08:58:59.929824Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T08:59:00.00772Z","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-08-19T08:59:00.007822Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T08:59:00.008106Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T08:59:00.008155Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T08:59:00.007881Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T08:59:00.011279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-08-19T08:59:00.012528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T08:59:00.059238Z","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-08-19T08:59:00.059384Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T08:59:00.059411Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-19T08:59:13.364134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845538080267850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T08:59:13.694873Z","caller":"traceutil/trace.go:171","msg":"trace[1511518576] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"831.847499ms","start":"2026-08-19T08:59:12.862998Z","end":"2026-08-19T08:59:13.694846Z","steps":["trace[1511518576] 'read index received' (duration: 831.708654ms)","trace[1511518576] 'applied index is now lower than readState.Index' (duration: 137.775µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T08:59:13.694964Z","caller":"traceutil/trace.go:171","msg":"trace[1413874176] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"836.410969ms","start":"2026-08-19T08:59:12.858519Z","end":"2026-08-19T08:59:13.69493Z","steps":["trace[1413874176] 'process raft request' (duration: 836.173751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:13.69511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.148007ms","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-08-19T08:59:13.695188Z","caller":"traceutil/trace.go:171","msg":"trace[1010804446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"378.339494ms","start":"2026-08-19T08:59:13.316833Z","end":"2026-08-19T08:59:13.695173Z","steps":["trace[1010804446] 'agreement among raft nodes before linearized reading' (duration: 378.160298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:13.695186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.380574ms","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-08-19T08:59:13.695178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.121078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T08:59:13.695258Z","caller":"traceutil/trace.go:171","msg":"trace[470484479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:360; }","duration":"321.480968ms","start":"2026-08-19T08:59:13.373752Z","end":"2026-08-19T08:59:13.695233Z","steps":["trace[470484479] 'agreement among raft nodes before linearized reading' (duration: 321.273771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:13.695237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:13.316799Z","time spent":"378.426797ms","remote":"127.0.0.1:41704","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-08-19T08:59:13.695298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:13.373738Z","time spent":"321.55213ms","remote":"127.0.0.1:41704","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-08-19T08:59:13.695417Z","caller":"traceutil/trace.go:171","msg":"trace[1717879639] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:360; }","duration":"832.310804ms","start":"2026-08-19T08:59:12.862964Z","end":"2026-08-19T08:59:13.695275Z","steps":["trace[1717879639] 'agreement among raft nodes before linearized reading' (duration: 831.991584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:13.695479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:12.862949Z","time spent":"832.516291ms","remote":"127.0.0.1:41510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-08-19T08:59:13.695659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:12.858484Z","time spent":"836.566004ms","remote":"127.0.0.1:41704","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-08-19T08:59:24.22748Z","caller":"traceutil/trace.go:171","msg":"trace[995634744] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"480.509813ms","start":"2026-08-19T08:59:23.746943Z","end":"2026-08-19T08:59:24.227453Z","steps":["trace[995634744] 'read index received' (duration: 480.4217ms)","trace[995634744] 'applied index is now lower than readState.Index' (duration: 86.523µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T08:59:24.227732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.743461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T08:59:24.227837Z","caller":"traceutil/trace.go:171","msg":"trace[1600940891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"480.897476ms","start":"2026-08-19T08:59:23.746915Z","end":"2026-08-19T08:59:24.227813Z","steps":["trace[1600940891] 'agreement among raft nodes before linearized reading' (duration: 480.647867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:24.227895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:23.746908Z","time spent":"480.976869ms","remote":"127.0.0.1:41704","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-08-19T08:59:24.228305Z","caller":"traceutil/trace.go:171","msg":"trace[302247423] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"482.060186ms","start":"2026-08-19T08:59:23.746223Z","end":"2026-08-19T08:59:24.228283Z","steps":["trace[302247423] 'process raft request' (duration: 481.064252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:24.228424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:23.746177Z","time spent":"482.175571ms","remote":"127.0.0.1:41704","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-08-19T08:59:24.235218Z","caller":"traceutil/trace.go:171","msg":"trace[2028667002] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"485.113882ms","start":"2026-08-19T08:59:23.750063Z","end":"2026-08-19T08:59:24.235177Z","steps":["trace[2028667002] 'process raft request' (duration: 484.854693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:24.235226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.042022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T08:59:24.235545Z","caller":"traceutil/trace.go:171","msg":"trace[2001040479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:392; }","duration":"121.451536ms","start":"2026-08-19T08:59:24.11407Z","end":"2026-08-19T08:59:24.235521Z","steps":["trace[2001040479] 'agreement among raft nodes before linearized reading' (duration: 121.012151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:24.235611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:23.750044Z","time spent":"485.308498ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:48.335656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.856895ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T08:59:52.410945Z","caller":"traceutil/trace.go:171","msg":"trace[1248177877] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"440.190186ms","start":"2026-08-19T08:59:51.970715Z","end":"2026-08-19T08:59:52.410906Z","steps":["trace[1248177877] 'process raft request' (duration: 427.714657ms)","trace[1248177877] 'compare' (duration: 12.263561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T08:59:52.411113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.970702Z","time spent":"440.32841ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T08:59:52.411854Z","caller":"traceutil/trace.go:171","msg":"trace[1533299072] transaction","detail":"{read_only:false; number_of_response:0; response_revision:541; }","duration":"432.300846ms","start":"2026-08-19T08:59:51.979514Z","end":"2026-08-19T08:59:52.411815Z","steps":["trace[1533299072] 'process raft request' (duration: 432.150311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:52.412982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.979498Z","time spent":"433.430188ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T08:59:52.412094Z","caller":"traceutil/trace.go:171","msg":"trace[1163730415] transaction","detail":"{read_only:false; number_of_response:0; response_revision:542; }","duration":"432.488443ms","start":"2026-08-19T08:59:51.979587Z","end":"2026-08-19T08:59:52.412075Z","steps":["trace[1163730415] 'process raft request' (duration: 432.12829ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412086Z","caller":"traceutil/trace.go:171","msg":"trace[969411122] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"441.031956ms","start":"2026-08-19T08:59:51.971034Z","end":"2026-08-19T08:59:52.412066Z","steps":["trace[969411122] 'process raft request' (duration: 440.560449ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412148Z","caller":"traceutil/trace.go:171","msg":"trace[876725689] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"428.85214ms","start":"2026-08-19T08:59:51.983286Z","end":"2026-08-19T08:59:52.412138Z","steps":["trace[876725689] 'process raft request' (duration: 428.480206ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412189Z","caller":"traceutil/trace.go:171","msg":"trace[1266103954] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"423.928299ms","start":"2026-08-19T08:59:51.988252Z","end":"2026-08-19T08:59:52.412181Z","steps":["trace[1266103954] 'process raft request' (duration: 423.544615ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412252Z","caller":"traceutil/trace.go:171","msg":"trace[685795800] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"432.554185ms","start":"2026-08-19T08:59:51.979672Z","end":"2026-08-19T08:59:52.412226Z","steps":["trace[685795800] 'process raft request' (duration: 432.062497ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412305Z","caller":"traceutil/trace.go:171","msg":"trace[653892213] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"365.69357ms","start":"2026-08-19T08:59:52.046603Z","end":"2026-08-19T08:59:52.412297Z","steps":["trace[653892213] 'process raft request' (duration: 365.626737ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412314Z","caller":"traceutil/trace.go:171","msg":"trace[659725940] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"401.242136ms","start":"2026-08-19T08:59:52.011062Z","end":"2026-08-19T08:59:52.412304Z","steps":["trace[659725940] 'process raft request' (duration: 400.844532ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T08:59:52.412326Z","caller":"traceutil/trace.go:171","msg":"trace[936886948] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"432.767933ms","start":"2026-08-19T08:59:51.979533Z","end":"2026-08-19T08:59:52.412301Z","steps":["trace[936886948] 'process raft request' (duration: 432.15191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:52.413313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.979582Z","time spent":"433.703368ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T08:59:52.414014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.979516Z","time spent":"434.464026ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:52.413578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.988243Z","time spent":"425.289729ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:52.413596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.971027Z","time spent":"442.431648ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:52.413588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:52.04659Z","time spent":"366.956056ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T08:59:52.413635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.979664Z","time spent":"433.845553ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:52.413933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:52.011034Z","time spent":"402.862476ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T08:59:52.416141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:51.983278Z","time spent":"430.149137ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7374,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T08:59:58.573786Z","caller":"traceutil/trace.go:171","msg":"trace[640065854] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"125.162361ms","start":"2026-08-19T08:59:58.4486Z","end":"2026-08-19T08:59:58.573762Z","steps":["trace[640065854] 'read index received' (duration: 124.425333ms)","trace[640065854] 'applied index is now lower than readState.Index' (duration: 736.308µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T08:59:58.573912Z","caller":"traceutil/trace.go:171","msg":"trace[1498008803] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"167.409419ms","start":"2026-08-19T08:59:58.406451Z","end":"2026-08-19T08:59:58.57386Z","steps":["trace[1498008803] 'process raft request' (duration: 166.825268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:58.574059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.43464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T08:59:58.574161Z","caller":"traceutil/trace.go:171","msg":"trace[1257826853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"125.579546ms","start":"2026-08-19T08:59:58.44856Z","end":"2026-08-19T08:59:58.574139Z","steps":["trace[1257826853] 'agreement among raft nodes before linearized reading' (duration: 125.322446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:59.168024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.096306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T08:59:59.168268Z","caller":"traceutil/trace.go:171","msg":"trace[154294809] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"580.906434ms","start":"2026-08-19T08:59:58.587342Z","end":"2026-08-19T08:59:59.168248Z","steps":["trace[154294809] 'process raft request' (duration: 580.849132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:59.168339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:58.587302Z","time spent":"581.008187ms","remote":"127.0.0.1:41652","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-08-19T08:59:59.168482Z","caller":"traceutil/trace.go:171","msg":"trace[710995246] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"587.874902ms","start":"2026-08-19T08:59:58.580578Z","end":"2026-08-19T08:59:59.168452Z","steps":["trace[710995246] 'process raft request' (duration: 384.267167ms)","trace[710995246] 'compare' (duration: 202.789995ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T08:59:59.168554Z","caller":"traceutil/trace.go:171","msg":"trace[878854147] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"587.832331ms","start":"2026-08-19T08:59:58.580715Z","end":"2026-08-19T08:59:59.168547Z","steps":["trace[878854147] 'process raft request' (duration: 587.442726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:59.168609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:58.580692Z","time spent":"587.889103ms","remote":"127.0.0.1:41704","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-08-19T08:59:59.16866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:58.580567Z","time spent":"587.997176ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T08:59:59.168525Z","caller":"traceutil/trace.go:171","msg":"trace[464472229] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"587.896343ms","start":"2026-08-19T08:59:58.580619Z","end":"2026-08-19T08:59:59.168516Z","steps":["trace[464472229] 'process raft request' (duration: 587.492718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:59.169047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:58.580614Z","time spent":"588.394952ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T08:59:59.199771Z","caller":"traceutil/trace.go:171","msg":"trace[1744485223] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"578.308169ms","start":"2026-08-19T08:59:58.621437Z","end":"2026-08-19T08:59:59.199745Z","steps":["trace[1744485223] 'process raft request' (duration: 578.171274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T08:59:59.19992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:58.621416Z","time spent":"578.435204ms","remote":"127.0.0.1:41682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:00:01.2054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81434189s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:00:01.205496Z","caller":"traceutil/trace.go:171","msg":"trace[1185181765] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.741572752s","start":"2026-08-19T08:59:59.463906Z","end":"2026-08-19T09:00:01.205479Z","steps":["trace[1185181765] 'read index received' (duration: 76.843µs)","trace[1185181765] 'applied index is now lower than readState.Index' (duration: 1.741494799s)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:00:01.205695Z","caller":"traceutil/trace.go:171","msg":"trace[87863582] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.988548753s","start":"2026-08-19T08:59:59.217134Z","end":"2026-08-19T09:00:01.205683Z","steps":["trace[87863582] 'process raft request' (duration: 173.86127ms)","trace[87863582] 'compare' (duration: 1.814103901s)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:00:01.205746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:59.21712Z","time spent":"1.988601035s","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:00:01.205959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742071721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:00:01.205995Z","caller":"traceutil/trace.go:171","msg":"trace[900383443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.742152214s","start":"2026-08-19T08:59:59.463833Z","end":"2026-08-19T09:00:01.205986Z","steps":["trace[900383443] 'agreement among raft nodes before linearized reading' (duration: 1.742066951s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:01.206048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:59.463812Z","time spent":"1.742228097s","remote":"127.0.0.1:41476","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-08-19T09:00:01.206034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702125624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:00:01.206153Z","caller":"traceutil/trace.go:171","msg":"trace[1417565097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.702235038s","start":"2026-08-19T08:59:59.503881Z","end":"2026-08-19T09:00:01.206116Z","steps":["trace[1417565097] 'agreement among raft nodes before linearized reading' (duration: 1.702082152s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:01.206202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T08:59:59.503833Z","time spent":"1.702360283s","remote":"127.0.0.1:41472","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-08-19T09:00:01.206435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.091404ms","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-08-19T09:00:01.206468Z","caller":"traceutil/trace.go:171","msg":"trace[545963336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"526.180268ms","start":"2026-08-19T09:00:00.680279Z","end":"2026-08-19T09:00:01.206459Z","steps":["trace[545963336] 'agreement among raft nodes before linearized reading' (duration: 526.061264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:01.206494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:00:00.680257Z","time spent":"526.232079ms","remote":"127.0.0.1:41704","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-08-19T09:00:01.36094Z","caller":"traceutil/trace.go:171","msg":"trace[2018747636] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"151.093622ms","start":"2026-08-19T09:00:01.209816Z","end":"2026-08-19T09:00:01.36091Z","steps":["trace[2018747636] 'process raft request' (duration: 144.551488ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.365749Z","caller":"traceutil/trace.go:171","msg":"trace[666813136] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:662; }","duration":"150.032442ms","start":"2026-08-19T09:00:01.215698Z","end":"2026-08-19T09:00:01.36573Z","steps":["trace[666813136] 'read index received' (duration: 138.71714ms)","trace[666813136] 'applied index is now lower than readState.Index' (duration: 11.314412ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:00:01.365873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.157027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:00:01.365917Z","caller":"traceutil/trace.go:171","msg":"trace[1028603446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"150.22568ms","start":"2026-08-19T09:00:01.215679Z","end":"2026-08-19T09:00:01.365905Z","steps":["trace[1028603446] 'agreement among raft nodes before linearized reading' (duration: 150.135616ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366035Z","caller":"traceutil/trace.go:171","msg":"trace[1804222741] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"154.835002ms","start":"2026-08-19T09:00:01.211181Z","end":"2026-08-19T09:00:01.366016Z","steps":["trace[1804222741] 'process raft request' (duration: 154.408506ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366178Z","caller":"traceutil/trace.go:171","msg":"trace[259415546] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"154.305802ms","start":"2026-08-19T09:00:01.211819Z","end":"2026-08-19T09:00:01.366124Z","steps":["trace[259415546] 'process raft request' (duration: 153.847275ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366201Z","caller":"traceutil/trace.go:171","msg":"trace[145207390] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"154.224798ms","start":"2026-08-19T09:00:01.211936Z","end":"2026-08-19T09:00:01.366161Z","steps":["trace[145207390] 'process raft request' (duration: 153.765311ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366301Z","caller":"traceutil/trace.go:171","msg":"trace[69428003] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"155.230326ms","start":"2026-08-19T09:00:01.21106Z","end":"2026-08-19T09:00:01.36629Z","steps":["trace[69428003] 'process raft request' (duration: 154.368764ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366464Z","caller":"traceutil/trace.go:171","msg":"trace[1717087288] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"155.108802ms","start":"2026-08-19T09:00:01.211343Z","end":"2026-08-19T09:00:01.366451Z","steps":["trace[1717087288] 'process raft request' (duration: 154.179458ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366495Z","caller":"traceutil/trace.go:171","msg":"trace[162501121] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"154.921885ms","start":"2026-08-19T09:00:01.21156Z","end":"2026-08-19T09:00:01.366482Z","steps":["trace[162501121] 'process raft request' (duration: 154.070594ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:01.366573Z","caller":"traceutil/trace.go:171","msg":"trace[1936724659] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"155.115212ms","start":"2026-08-19T09:00:01.211416Z","end":"2026-08-19T09:00:01.366531Z","steps":["trace[1936724659] 'process raft request' (duration: 154.141156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:02.9775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.304722ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:00:02.977583Z","caller":"traceutil/trace.go:171","msg":"trace[2034262035] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:665; }","duration":"142.396555ms","start":"2026-08-19T09:00:02.835172Z","end":"2026-08-19T09:00:02.977569Z","steps":["trace[2034262035] 'range keys from in-memory index tree' (duration: 142.291732ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:02.979507Z","caller":"traceutil/trace.go:171","msg":"trace[312472595] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"108.577981ms","start":"2026-08-19T09:00:02.870886Z","end":"2026-08-19T09:00:02.979464Z","steps":["trace[312472595] 'read index received' (duration: 108.521099ms)","trace[312472595] 'applied index is now lower than readState.Index' (duration: 55.672µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:00:02.979602Z","caller":"traceutil/trace.go:171","msg":"trace[1536618142] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"210.874386ms","start":"2026-08-19T09:00:02.768701Z","end":"2026-08-19T09:00:02.979576Z","steps":["trace[1536618142] 'process raft request' (duration: 210.644238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:02.979818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.867331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T09:00:02.979906Z","caller":"traceutil/trace.go:171","msg":"trace[593159058] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:666; }","duration":"109.053229ms","start":"2026-08-19T09:00:02.870828Z","end":"2026-08-19T09:00:02.979881Z","steps":["trace[593159058] 'agreement among raft nodes before linearized reading' (duration: 108.765268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:17.521803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017604474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:00:17.521889Z","caller":"traceutil/trace.go:171","msg":"trace[1734113220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"1.017734538s","start":"2026-08-19T09:00:16.504138Z","end":"2026-08-19T09:00:17.521873Z","steps":["trace[1734113220] 'range keys from in-memory index tree' (duration: 1.017539121s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:17.521923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:00:16.504115Z","time spent":"1.017800121s","remote":"127.0.0.1:41476","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-08-19T09:00:17.522124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.417118ms","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-08-19T09:00:17.522197Z","caller":"traceutil/trace.go:171","msg":"trace[772246250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"876.529772ms","start":"2026-08-19T09:00:16.64565Z","end":"2026-08-19T09:00:17.52218Z","steps":["trace[772246250] 'range keys from in-memory index tree' (duration: 876.273352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:17.522225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.087571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kwnrm\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-08-19T09:00:17.522234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:00:16.645635Z","time spent":"876.590774ms","remote":"127.0.0.1:41704","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-08-19T09:00:17.522263Z","caller":"traceutil/trace.go:171","msg":"trace[695066909] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kwnrm; range_end:; response_count:1; response_revision:799; }","duration":"900.123943ms","start":"2026-08-19T09:00:16.622128Z","end":"2026-08-19T09:00:17.522252Z","steps":["trace[695066909] 'range keys from in-memory index tree' (duration: 899.965786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:17.522292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:00:16.622107Z","time spent":"900.178114ms","remote":"127.0.0.1:41652","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-kwnrm\" "} {"level":"warn","ts":"2026-08-19T09:00:17.52235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.754548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:00:17.522381Z","caller":"traceutil/trace.go:171","msg":"trace[983313013] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:799; }","duration":"764.81007ms","start":"2026-08-19T09:00:16.757562Z","end":"2026-08-19T09:00:17.522372Z","steps":["trace[983313013] 'count revisions from in-memory index tree' (duration: 763.862604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:17.522412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:00:16.757549Z","time spent":"764.855812ms","remote":"127.0.0.1:41786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:00:51.292019Z","caller":"traceutil/trace.go:171","msg":"trace[284241452] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"109.149785ms","start":"2026-08-19T09:00:51.182828Z","end":"2026-08-19T09:00:51.291978Z","steps":["trace[284241452] 'read index received' (duration: 108.430197ms)","trace[284241452] 'applied index is now lower than readState.Index' (duration: 718.138µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:00:51.292094Z","caller":"traceutil/trace.go:171","msg":"trace[1072930871] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1257; }","duration":"112.123211ms","start":"2026-08-19T09:00:51.179944Z","end":"2026-08-19T09:00:51.292067Z","steps":["trace[1072930871] 'process raft request' (duration: 111.310559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:00:51.292443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.587672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/self-signed-ca-2lmbb\" ","response":"range_response_count:1 size:997"} {"level":"info","ts":"2026-08-19T09:00:51.292525Z","caller":"traceutil/trace.go:171","msg":"trace[218929976] range","detail":"{range_begin:/registry/secrets/cert-manager/self-signed-ca-2lmbb; range_end:; response_count:1; response_revision:1257; }","duration":"109.709287ms","start":"2026-08-19T09:00:51.182797Z","end":"2026-08-19T09:00:51.292507Z","steps":["trace[218929976] 'agreement among raft nodes before linearized reading' (duration: 109.327452ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:00:51.292912Z","caller":"traceutil/trace.go:171","msg":"trace[462766845] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"108.936826ms","start":"2026-08-19T09:00:51.183957Z","end":"2026-08-19T09:00:51.292894Z","steps":["trace[462766845] 'process raft request' (duration: 108.79782ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:19.978489Z","caller":"traceutil/trace.go:171","msg":"trace[937057230] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1751; }","duration":"112.07369ms","start":"2026-08-19T09:01:19.866399Z","end":"2026-08-19T09:01:19.978473Z","steps":["trace[937057230] 'read index received' (duration: 108.883222ms)","trace[937057230] 'applied index is now lower than readState.Index' (duration: 3.189858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:19.978659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.242826ms","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-08-19T09:01:19.978655Z","caller":"traceutil/trace.go:171","msg":"trace[1830099904] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"125.917915ms","start":"2026-08-19T09:01:19.852707Z","end":"2026-08-19T09:01:19.978625Z","steps":["trace[1830099904] 'process raft request' (duration: 122.646644ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:19.978682Z","caller":"traceutil/trace.go:171","msg":"trace[899152638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1682; }","duration":"112.302999ms","start":"2026-08-19T09:01:19.866373Z","end":"2026-08-19T09:01:19.978676Z","steps":["trace[899152638] 'agreement among raft nodes before linearized reading' (duration: 112.168153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:38.459824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.740478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:01:38.460456Z","caller":"traceutil/trace.go:171","msg":"trace[224459374] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"848.403049ms","start":"2026-08-19T09:01:37.611986Z","end":"2026-08-19T09:01:38.460389Z","steps":["trace[224459374] 'process raft request' (duration: 39.967763ms)","trace[224459374] 'compare' (duration: 807.607172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:38.460668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.41312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-19T09:01:38.460692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.611958Z","time spent":"848.566585ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:38.460756Z","caller":"traceutil/trace.go:171","msg":"trace[223373446] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b; range_end:; response_count:1; response_revision:1892; }","duration":"733.548045ms","start":"2026-08-19T09:01:37.727186Z","end":"2026-08-19T09:01:38.460734Z","steps":["trace[223373446] 'agreement among raft nodes before linearized reading' (duration: 733.294295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:38.460803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.727165Z","time spent":"733.628588ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b\" "} {"level":"info","ts":"2026-08-19T09:01:38.461282Z","caller":"traceutil/trace.go:171","msg":"trace[1671768687] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"733.095827ms","start":"2026-08-19T09:01:37.727232Z","end":"2026-08-19T09:01:38.460328Z","steps":["trace[1671768687] 'read index received' (duration: 37.852µs)","trace[1671768687] 'applied index is now lower than readState.Index' (duration: 733.055615ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:01:38.523048Z","caller":"traceutil/trace.go:171","msg":"trace[732379427] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"729.621598ms","start":"2026-08-19T09:01:37.793405Z","end":"2026-08-19T09:01:38.523027Z","steps":["trace[732379427] 'process raft request' (duration: 729.503953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:38.523174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.793386Z","time spent":"729.725841ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:38.870734Z","caller":"traceutil/trace.go:171","msg":"trace[491595397] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"869.042637ms","start":"2026-08-19T09:01:38.001654Z","end":"2026-08-19T09:01:38.870696Z","steps":["trace[491595397] 'process raft request' (duration: 805.587566ms)","trace[491595397] 'compare' (duration: 63.137218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:38.870914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.001626Z","time spent":"869.194373ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:38.871153Z","caller":"traceutil/trace.go:171","msg":"trace[2105417857] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"805.302064ms","start":"2026-08-19T09:01:38.065824Z","end":"2026-08-19T09:01:38.871126Z","steps":["trace[2105417857] 'process raft request' (duration: 804.787443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:38.871613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.065803Z","time spent":"805.422629ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:01:39.677225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.521661ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:01:39.67755Z","caller":"traceutil/trace.go:171","msg":"trace[2008496576] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"567.148312ms","start":"2026-08-19T09:01:39.110382Z","end":"2026-08-19T09:01:39.677531Z","steps":["trace[2008496576] 'process raft request' (duration: 567.093849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.677689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:39.110356Z","time spent":"567.265036ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:01:39.677796Z","caller":"traceutil/trace.go:171","msg":"trace[1633768843] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1969; }","duration":"1.216432472s","start":"2026-08-19T09:01:38.461331Z","end":"2026-08-19T09:01:39.677764Z","steps":["trace[1633768843] 'read index received' (duration: 61.632338ms)","trace[1633768843] 'applied index is now lower than readState.Index' (duration: 1.154797144s)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:01:39.677924Z","caller":"traceutil/trace.go:171","msg":"trace[1180742470] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"1.170130333s","start":"2026-08-19T09:01:38.507771Z","end":"2026-08-19T09:01:39.677901Z","steps":["trace[1180742470] 'process raft request' (duration: 1.16955858s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.507744Z","time spent":"1.170206456s","remote":"127.0.0.1:41636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7763,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:01:39.678048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632188947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:01:39.678093Z","caller":"traceutil/trace.go:171","msg":"trace[913036190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"1.632266411s","start":"2026-08-19T09:01:38.045814Z","end":"2026-08-19T09:01:39.67808Z","steps":["trace[913036190] 'agreement among raft nodes before linearized reading' (duration: 1.632121335s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562518346s","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-08-19T09:01:39.67813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.045801Z","time spent":"1.632321072s","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:01:39.67815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77181748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:01:39.678176Z","caller":"traceutil/trace.go:171","msg":"trace[1637269859] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"1.56262372s","start":"2026-08-19T09:01:38.115524Z","end":"2026-08-19T09:01:39.678148Z","steps":["trace[1637269859] 'agreement among raft nodes before linearized reading' (duration: 1.562417052s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:39.678197Z","caller":"traceutil/trace.go:171","msg":"trace[1623725214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"1.771879843s","start":"2026-08-19T09:01:37.906306Z","end":"2026-08-19T09:01:39.678186Z","steps":["trace[1623725214] 'agreement among raft nodes before linearized reading' (duration: 1.771728517s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621552268s","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-08-19T09:01:39.678226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.115512Z","time spent":"1.562704993s","remote":"127.0.0.1:53948","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-08-19T09:01:39.678229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.906299Z","time spent":"1.771923434s","remote":"127.0.0.1:41704","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-08-19T09:01:39.678252Z","caller":"traceutil/trace.go:171","msg":"trace[2107898407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1897; }","duration":"1.621640732s","start":"2026-08-19T09:01:38.056597Z","end":"2026-08-19T09:01:39.678238Z","steps":["trace[2107898407] 'agreement among raft nodes before linearized reading' (duration: 1.621432374s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674180562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-19T09:01:39.67829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.05658Z","time spent":"1.621702624s","remote":"127.0.0.1:41704","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-08-19T09:01:39.678297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784478521s","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-08-19T09:01:39.678317Z","caller":"traceutil/trace.go:171","msg":"trace[1424157979] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b; range_end:; response_count:1; response_revision:1897; }","duration":"1.674253265s","start":"2026-08-19T09:01:38.004053Z","end":"2026-08-19T09:01:39.678307Z","steps":["trace[1424157979] 'agreement among raft nodes before linearized reading' (duration: 1.674162701s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:39.678332Z","caller":"traceutil/trace.go:171","msg":"trace[1797929365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"1.784534483s","start":"2026-08-19T09:01:37.893785Z","end":"2026-08-19T09:01:39.678319Z","steps":["trace[1797929365] 'agreement among raft nodes before linearized reading' (duration: 1.784434929s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.004034Z","time spent":"1.674308437s","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b\" "} {"level":"warn","ts":"2026-08-19T09:01:39.678344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288242029s","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-08-19T09:01:39.67836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.89377Z","time spent":"1.784583225s","remote":"127.0.0.1:41704","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-08-19T09:01:39.678396Z","caller":"traceutil/trace.go:171","msg":"trace[714355912] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"1.288347834s","start":"2026-08-19T09:01:38.390031Z","end":"2026-08-19T09:01:39.678379Z","steps":["trace[714355912] 'agreement among raft nodes before linearized reading' (duration: 1.288223129s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:38.390005Z","time spent":"1.288415166s","remote":"127.0.0.1:53948","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-08-19T09:01:39.67842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.799524768s","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-08-19T09:01:39.678471Z","caller":"traceutil/trace.go:171","msg":"trace[391071399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"1.799610301s","start":"2026-08-19T09:01:37.878844Z","end":"2026-08-19T09:01:39.678455Z","steps":["trace[391071399] 'agreement among raft nodes before linearized reading' (duration: 1.799490666s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.678504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:37.878817Z","time spent":"1.799680184s","remote":"127.0.0.1:41704","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-08-19T09:01:39.688762Z","caller":"traceutil/trace.go:171","msg":"trace[769918633] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"524.742319ms","start":"2026-08-19T09:01:39.164004Z","end":"2026-08-19T09:01:39.688746Z","steps":["trace[769918633] 'process raft request' (duration: 524.640675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.688902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.900606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T09:01:39.688963Z","caller":"traceutil/trace.go:171","msg":"trace[255582255] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0bda8a8-154e-4c3c-9d60-955b9b5f861b; range_end:; response_count:1; response_revision:1898; }","duration":"224.029031ms","start":"2026-08-19T09:01:39.464923Z","end":"2026-08-19T09:01:39.688952Z","steps":["trace[255582255] 'agreement among raft nodes before linearized reading' (duration: 223.831583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:39.163975Z","time spent":"524.842283ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:01:39.68894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.220677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cd295a78cb82d6\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-08-19T09:01:39.68918Z","caller":"traceutil/trace.go:171","msg":"trace[1378669732] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cd295a78cb82d6; range_end:; response_count:1; response_revision:1898; }","duration":"604.505229ms","start":"2026-08-19T09:01:39.084656Z","end":"2026-08-19T09:01:39.689161Z","steps":["trace[1378669732] 'agreement among raft nodes before linearized reading' (duration: 604.091392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.689225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:39.084631Z","time spent":"604.576702ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cd295a78cb82d6\" "} {"level":"warn","ts":"2026-08-19T09:01:39.689023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.788332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:01:39.68926Z","caller":"traceutil/trace.go:171","msg":"trace[1046368962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"225.059674ms","start":"2026-08-19T09:01:39.464192Z","end":"2026-08-19T09:01:39.689252Z","steps":["trace[1046368962] 'agreement among raft nodes before linearized reading' (duration: 224.807993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:39.689018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.611941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:01:39.689351Z","caller":"traceutil/trace.go:171","msg":"trace[209284679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"185.960145ms","start":"2026-08-19T09:01:39.503381Z","end":"2026-08-19T09:01:39.689341Z","steps":["trace[209284679] 'agreement among raft nodes before linearized reading' (duration: 185.604871ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:01:42.141892Z","caller":"traceutil/trace.go:171","msg":"trace[1572540184] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"100.941188ms","start":"2026-08-19T09:01:42.040925Z","end":"2026-08-19T09:01:42.141866Z","steps":["trace[1572540184] 'process raft request' (duration: 100.786112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:51.36354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.154924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:01:51.363653Z","caller":"traceutil/trace.go:171","msg":"trace[147993216] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"357.694948ms","start":"2026-08-19T09:01:51.00594Z","end":"2026-08-19T09:01:51.363635Z","steps":["trace[147993216] 'read index received' (duration: 39.672µs)","trace[147993216] 'applied index is now lower than readState.Index' (duration: 357.654186ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:01:51.363756Z","caller":"traceutil/trace.go:171","msg":"trace[1346751466] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"433.056691ms","start":"2026-08-19T09:01:50.930686Z","end":"2026-08-19T09:01:51.363743Z","steps":["trace[1346751466] 'process raft request' (duration: 20.591164ms)","trace[1346751466] 'compare' (duration: 411.743537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:01:51.363824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:50.930662Z","time spent":"433.130354ms","remote":"127.0.0.1:41704","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-08-19T09:01:51.363909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.022941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:01:51.364027Z","caller":"traceutil/trace.go:171","msg":"trace[2070625842] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2008; }","duration":"120.191629ms","start":"2026-08-19T09:01:51.243797Z","end":"2026-08-19T09:01:51.363988Z","steps":["trace[2070625842] 'agreement among raft nodes before linearized reading' (duration: 119.994341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:51.364306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.360685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"} {"level":"info","ts":"2026-08-19T09:01:51.364355Z","caller":"traceutil/trace.go:171","msg":"trace[1649895718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2008; }","duration":"358.443469ms","start":"2026-08-19T09:01:51.005901Z","end":"2026-08-19T09:01:51.364344Z","steps":["trace[1649895718] 'agreement among raft nodes before linearized reading' (duration: 358.276322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:01:51.364389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:01:51.005885Z","time spent":"358.4955ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12004,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-19T09:02:01.272537Z","caller":"traceutil/trace.go:171","msg":"trace[88226393] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"304.004763ms","start":"2026-08-19T09:02:00.968507Z","end":"2026-08-19T09:02:01.272512Z","steps":["trace[88226393] 'process raft request' (duration: 303.745112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:01.272698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:00.96847Z","time spent":"304.147798ms","remote":"127.0.0.1:41704","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-08-19T09:02:23.110016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.728433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:02:23.110125Z","caller":"traceutil/trace.go:171","msg":"trace[1776683257] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"156.001068ms","start":"2026-08-19T09:02:22.954106Z","end":"2026-08-19T09:02:23.110108Z","steps":["trace[1776683257] 'read index received' (duration: 42.096021ms)","trace[1776683257] 'applied index is now lower than readState.Index' (duration: 113.904067ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:23.110458Z","caller":"traceutil/trace.go:171","msg":"trace[1489630797] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"160.214377ms","start":"2026-08-19T09:02:22.950183Z","end":"2026-08-19T09:02:23.110397Z","steps":["trace[1489630797] 'process raft request' (duration: 46.020553ms)","trace[1489630797] 'compare' (duration: 113.513868ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:23.111052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.931261ms","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-08-19T09:02:23.111304Z","caller":"traceutil/trace.go:171","msg":"trace[1678348052] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"157.240238ms","start":"2026-08-19T09:02:22.954045Z","end":"2026-08-19T09:02:23.111285Z","steps":["trace[1678348052] 'agreement among raft nodes before linearized reading' (duration: 156.46694ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:23.50473Z","caller":"traceutil/trace.go:171","msg":"trace[2019039346] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"107.046975ms","start":"2026-08-19T09:02:23.39766Z","end":"2026-08-19T09:02:23.504707Z","steps":["trace[2019039346] 'process raft request' (duration: 106.84498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:23.72323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.400589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:02:23.723816Z","caller":"traceutil/trace.go:171","msg":"trace[1632282719] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2273; }","duration":"221.036953ms","start":"2026-08-19T09:02:23.502751Z","end":"2026-08-19T09:02:23.723788Z","steps":["trace[1632282719] 'read index received' (duration: 1.858164ms)","trace[1632282719] 'applied index is now lower than readState.Index' (duration: 219.176699ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:23.724005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.208298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:23.723804Z","caller":"traceutil/trace.go:171","msg":"trace[2144284360] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"263.975541ms","start":"2026-08-19T09:02:23.459785Z","end":"2026-08-19T09:02:23.723761Z","steps":["trace[2144284360] 'process raft request' (duration: 118.869625ms)","trace[2144284360] 'compare' (duration: 143.945458ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:23.724101Z","caller":"traceutil/trace.go:171","msg":"trace[1654077463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2187; }","duration":"221.386202ms","start":"2026-08-19T09:02:23.502693Z","end":"2026-08-19T09:02:23.72408Z","steps":["trace[1654077463] 'agreement among raft nodes before linearized reading' (duration: 221.190198ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:23.72389Z","caller":"traceutil/trace.go:171","msg":"trace[1306151450] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"244.994232ms","start":"2026-08-19T09:02:23.478886Z","end":"2026-08-19T09:02:23.72388Z","steps":["trace[1306151450] 'process raft request' (duration: 244.725285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:23.724838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.81698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:02:23.724926Z","caller":"traceutil/trace.go:171","msg":"trace[1159149658] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2187; }","duration":"139.992915ms","start":"2026-08-19T09:02:23.584915Z","end":"2026-08-19T09:02:23.724908Z","steps":["trace[1159149658] 'agreement among raft nodes before linearized reading' (duration: 139.811241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.636365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.805642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:02:24.636829Z","caller":"traceutil/trace.go:171","msg":"trace[264872197] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"782.261069ms","start":"2026-08-19T09:02:23.854537Z","end":"2026-08-19T09:02:24.636798Z","steps":["trace[264872197] 'process raft request' (duration: 545.927104ms)","trace[264872197] 'compare' (duration: 235.481235ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:24.636937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.854503Z","time spent":"782.385342ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:24.691547Z","caller":"traceutil/trace.go:171","msg":"trace[1434287942] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2276; }","duration":"789.971818ms","start":"2026-08-19T09:02:23.901551Z","end":"2026-08-19T09:02:24.691523Z","steps":["trace[1434287942] 'read index received' (duration: 498.932862ms)","trace[1434287942] 'applied index is now lower than readState.Index' (duration: 291.037476ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:24.69176Z","caller":"traceutil/trace.go:171","msg":"trace[264670548] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"837.093984ms","start":"2026-08-19T09:02:23.854597Z","end":"2026-08-19T09:02:24.691691Z","steps":["trace[264670548] 'process raft request' (duration: 836.813528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.854538Z","time spent":"837.378321ms","remote":"127.0.0.1:41704","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-08-19T09:02:24.692099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.450475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:02:24.692209Z","caller":"traceutil/trace.go:171","msg":"trace[193111581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"789.611839ms","start":"2026-08-19T09:02:23.902575Z","end":"2026-08-19T09:02:24.692187Z","steps":["trace[193111581] 'agreement among raft nodes before linearized reading' (duration: 789.21484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.902569Z","time spent":"789.688442ms","remote":"127.0.0.1:41704","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":"warn","ts":"2026-08-19T09:02:24.692462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.50765ms","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-08-19T09:02:24.692492Z","caller":"traceutil/trace.go:171","msg":"trace[47206023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2189; }","duration":"779.564382ms","start":"2026-08-19T09:02:23.912919Z","end":"2026-08-19T09:02:24.692483Z","steps":["trace[47206023] 'agreement among raft nodes before linearized reading' (duration: 779.47642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.912904Z","time spent":"779.621363ms","remote":"127.0.0.1:41704","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-08-19T09:02:24.692587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.011653ms","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-08-19T09:02:24.692667Z","caller":"traceutil/trace.go:171","msg":"trace[1564699126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2189; }","duration":"791.132756ms","start":"2026-08-19T09:02:23.901517Z","end":"2026-08-19T09:02:24.692649Z","steps":["trace[1564699126] 'agreement among raft nodes before linearized reading' (duration: 790.916071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.373074ms","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-08-19T09:02:24.692708Z","caller":"traceutil/trace.go:171","msg":"trace[546496161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"789.424645ms","start":"2026-08-19T09:02:23.903275Z","end":"2026-08-19T09:02:24.692699Z","steps":["trace[546496161] 'agreement among raft nodes before linearized reading' (duration: 789.332523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.901501Z","time spent":"791.198727ms","remote":"127.0.0.1:41704","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-08-19T09:02:24.692736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.903263Z","time spent":"789.466856ms","remote":"127.0.0.1:41704","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-08-19T09:02:24.692858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.144512ms","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-08-19T09:02:24.692898Z","caller":"traceutil/trace.go:171","msg":"trace[137669293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"790.215664ms","start":"2026-08-19T09:02:23.902674Z","end":"2026-08-19T09:02:24.69289Z","steps":["trace[137669293] 'agreement among raft nodes before linearized reading' (duration: 790.119601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.692929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:23.902657Z","time spent":"790.262104ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:02:24.693006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.000908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:24.693049Z","caller":"traceutil/trace.go:171","msg":"trace[1102887264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"189.08131ms","start":"2026-08-19T09:02:24.503957Z","end":"2026-08-19T09:02:24.693038Z","steps":["trace[1102887264] 'agreement among raft nodes before linearized reading' (duration: 189.019419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.693045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.272427ms","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-08-19T09:02:24.693212Z","caller":"traceutil/trace.go:171","msg":"trace[950251949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"574.964223ms","start":"2026-08-19T09:02:24.118226Z","end":"2026-08-19T09:02:24.69319Z","steps":["trace[950251949] 'agreement among raft nodes before linearized reading' (duration: 573.919458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:24.693349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:24.118211Z","time spent":"575.124187ms","remote":"127.0.0.1:53948","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":"info","ts":"2026-08-19T09:02:24.816267Z","caller":"traceutil/trace.go:171","msg":"trace[1286984819] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"117.009024ms","start":"2026-08-19T09:02:24.699235Z","end":"2026-08-19T09:02:24.816244Z","steps":["trace[1286984819] 'process raft request' (duration: 116.946653ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:24.816303Z","caller":"traceutil/trace.go:171","msg":"trace[1765731937] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"117.430325ms","start":"2026-08-19T09:02:24.698829Z","end":"2026-08-19T09:02:24.81626Z","steps":["trace[1765731937] 'process raft request' (duration: 47.48364ms)","trace[1765731937] 'compare' (duration: 69.75779ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:24.816578Z","caller":"traceutil/trace.go:171","msg":"trace[1302499422] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"117.22477ms","start":"2026-08-19T09:02:24.699329Z","end":"2026-08-19T09:02:24.816554Z","steps":["trace[1302499422] 'process raft request' (duration: 116.886281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:25.44284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845538080271582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:02:25.922573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.08033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:02:25.922653Z","caller":"traceutil/trace.go:171","msg":"trace[78298308] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"980.927735ms","start":"2026-08-19T09:02:24.941711Z","end":"2026-08-19T09:02:25.922639Z","steps":["trace[78298308] 'read index received' (duration: 853.687012ms)","trace[78298308] 'applied index is now lower than readState.Index' (duration: 127.239373ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:25.92293Z","caller":"traceutil/trace.go:171","msg":"trace[1354415846] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"992.703965ms","start":"2026-08-19T09:02:24.930179Z","end":"2026-08-19T09:02:25.922883Z","steps":["trace[1354415846] 'process raft request' (duration: 865.221266ms)","trace[1354415846] 'compare' (duration: 127.003077ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:25.92313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:24.930116Z","time spent":"992.906ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:02:25.923419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.628561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:25.924348Z","caller":"traceutil/trace.go:171","msg":"trace[935137186] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2195; }","duration":"982.697318ms","start":"2026-08-19T09:02:24.941634Z","end":"2026-08-19T09:02:25.924331Z","steps":["trace[935137186] 'agreement among raft nodes before linearized reading' (duration: 981.649992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:25.92445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:24.941623Z","time spent":"982.80575ms","remote":"127.0.0.1:41740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:02:25.934607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.983798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:25.934641Z","caller":"traceutil/trace.go:171","msg":"trace[222424868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2197; }","duration":"431.05548ms","start":"2026-08-19T09:02:25.503577Z","end":"2026-08-19T09:02:25.934633Z","steps":["trace[222424868] 'agreement among raft nodes before linearized reading' (duration: 430.961428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:25.93466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:25.503556Z","time spent":"431.099871ms","remote":"127.0.0.1:41472","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-08-19T09:02:25.934771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.793482ms","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-08-19T09:02:25.934767Z","caller":"traceutil/trace.go:171","msg":"trace[1641561290] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"425.240451ms","start":"2026-08-19T09:02:25.509491Z","end":"2026-08-19T09:02:25.934731Z","steps":["trace[1641561290] 'process raft request' (duration: 424.961735ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:25.934812Z","caller":"traceutil/trace.go:171","msg":"trace[1354979564] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2197; }","duration":"233.859483ms","start":"2026-08-19T09:02:25.700942Z","end":"2026-08-19T09:02:25.934801Z","steps":["trace[1354979564] 'agreement among raft nodes before linearized reading' (duration: 233.692369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:25.934907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:25.509444Z","time spent":"425.383635ms","remote":"127.0.0.1:41704","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-08-19T09:02:25.93525Z","caller":"traceutil/trace.go:171","msg":"trace[313191350] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"205.315521ms","start":"2026-08-19T09:02:25.729897Z","end":"2026-08-19T09:02:25.935213Z","steps":["trace[313191350] 'process raft request' (duration: 204.613034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:26.33976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.247382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:02:26.339902Z","caller":"traceutil/trace.go:171","msg":"trace[894587370] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"324.008307ms","start":"2026-08-19T09:02:26.015864Z","end":"2026-08-19T09:02:26.339873Z","steps":["trace[894587370] 'process raft request' (duration: 123.469947ms)","trace[894587370] 'compare' (duration: 200.008257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:26.340016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.015828Z","time spent":"324.14293ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:02:27.125494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.426081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-08-19T09:02:27.125531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.074244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:27.125561Z","caller":"traceutil/trace.go:171","msg":"trace[157577902] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2198; }","duration":"591.549114ms","start":"2026-08-19T09:02:26.533997Z","end":"2026-08-19T09:02:27.125546Z","steps":["trace[157577902] 'range keys from in-memory index tree' (duration: 591.262106ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:27.125582Z","caller":"traceutil/trace.go:171","msg":"trace[626553099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"621.172165ms","start":"2026-08-19T09:02:26.504398Z","end":"2026-08-19T09:02:27.12557Z","steps":["trace[626553099] 'range keys from in-memory index tree' (duration: 620.95841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.125593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.533976Z","time spent":"591.609615ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-19T09:02:27.125606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.504376Z","time spent":"621.226187ms","remote":"127.0.0.1:41476","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-08-19T09:02:27.125789Z","caller":"traceutil/trace.go:171","msg":"trace[1412414477] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"483.154928ms","start":"2026-08-19T09:02:26.642603Z","end":"2026-08-19T09:02:27.125758Z","steps":["trace[1412414477] 'process raft request' (duration: 464.932098ms)","trace[1412414477] 'compare' (duration: 18.047165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:02:27.125958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.64258Z","time spent":"483.281601ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:02:27.504756Z","caller":"traceutil/trace.go:171","msg":"trace[80901205] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"782.820176ms","start":"2026-08-19T09:02:26.721912Z","end":"2026-08-19T09:02:27.504732Z","steps":["trace[80901205] 'read index received' (duration: 385.581661ms)","trace[80901205] 'applied index is now lower than readState.Index' (duration: 397.237435ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:02:27.504805Z","caller":"traceutil/trace.go:171","msg":"trace[2071997832] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"748.626344ms","start":"2026-08-19T09:02:26.756154Z","end":"2026-08-19T09:02:27.504781Z","steps":["trace[2071997832] 'process raft request' (duration: 748.530382ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:27.504782Z","caller":"traceutil/trace.go:171","msg":"trace[2055031519] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"804.841725ms","start":"2026-08-19T09:02:26.699906Z","end":"2026-08-19T09:02:27.504747Z","steps":["trace[2055031519] 'process raft request' (duration: 804.62993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.504959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.699891Z","time spent":"804.980838ms","remote":"127.0.0.1:41704","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-08-19T09:02:27.50498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.756113Z","time spent":"748.749177ms","remote":"127.0.0.1:41704","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-08-19T09:02:27.505121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.122373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:02:27.505219Z","caller":"traceutil/trace.go:171","msg":"trace[1886061025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2201; }","duration":"783.324718ms","start":"2026-08-19T09:02:26.721882Z","end":"2026-08-19T09:02:27.505206Z","steps":["trace[1886061025] 'agreement among raft nodes before linearized reading' (duration: 782.96434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.505264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.721867Z","time spent":"783.386009ms","remote":"127.0.0.1:41704","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-08-19T09:02:27.511103Z","caller":"traceutil/trace.go:171","msg":"trace[1539819430] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"265.39985ms","start":"2026-08-19T09:02:27.245682Z","end":"2026-08-19T09:02:27.511082Z","steps":["trace[1539819430] 'process raft request' (duration: 265.198766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.511154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.17236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:02:27.511161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.243912ms","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-08-19T09:02:27.511209Z","caller":"traceutil/trace.go:171","msg":"trace[1977437561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2202; }","duration":"384.246072ms","start":"2026-08-19T09:02:27.126946Z","end":"2026-08-19T09:02:27.511192Z","steps":["trace[1977437561] 'agreement among raft nodes before linearized reading' (duration: 384.1581ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:02:27.511219Z","caller":"traceutil/trace.go:171","msg":"trace[1184372461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2202; }","duration":"690.338524ms","start":"2026-08-19T09:02:26.820867Z","end":"2026-08-19T09:02:27.511206Z","steps":["trace[1184372461] 'agreement among raft nodes before linearized reading' (duration: 690.198751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.511251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:27.126935Z","time spent":"384.308673ms","remote":"127.0.0.1:41472","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-08-19T09:02:27.511253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.820853Z","time spent":"690.392345ms","remote":"127.0.0.1:41704","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-08-19T09:02:27.511232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.461187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:02:27.511308Z","caller":"traceutil/trace.go:171","msg":"trace[1178986190] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2202; }","duration":"772.58068ms","start":"2026-08-19T09:02:26.73871Z","end":"2026-08-19T09:02:27.511291Z","steps":["trace[1178986190] 'agreement among raft nodes before linearized reading' (duration: 772.451787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.51136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.738697Z","time spent":"772.653672ms","remote":"127.0.0.1:53374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:02:27.511425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.918638ms","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-08-19T09:02:27.511466Z","caller":"traceutil/trace.go:171","msg":"trace[1683823204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2202; }","duration":"690.982869ms","start":"2026-08-19T09:02:26.820474Z","end":"2026-08-19T09:02:27.511457Z","steps":["trace[1683823204] 'agreement among raft nodes before linearized reading' (duration: 690.825366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.511497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.820463Z","time spent":"691.02618ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:02:27.511722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.533782ms","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-08-19T09:02:27.511797Z","caller":"traceutil/trace.go:171","msg":"trace[2141387117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2202; }","duration":"691.615235ms","start":"2026-08-19T09:02:26.820146Z","end":"2026-08-19T09:02:27.511761Z","steps":["trace[2141387117] 'agreement among raft nodes before linearized reading' (duration: 690.99539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.51179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.79629ms","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-08-19T09:02:27.511842Z","caller":"traceutil/trace.go:171","msg":"trace[999190654] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2202; }","duration":"378.870171ms","start":"2026-08-19T09:02:27.132962Z","end":"2026-08-19T09:02:27.511832Z","steps":["trace[999190654] 'agreement among raft nodes before linearized reading' (duration: 378.767549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.51188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:27.132946Z","time spent":"378.924113ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-19T09:02:27.511972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.268798ms","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-08-19T09:02:27.512832Z","caller":"traceutil/trace.go:171","msg":"trace[291082439] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2202; }","duration":"571.168059ms","start":"2026-08-19T09:02:26.941644Z","end":"2026-08-19T09:02:27.512812Z","steps":["trace[291082439] 'agreement among raft nodes before linearized reading' (duration: 569.395046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.512994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.941616Z","time spent":"571.362064ms","remote":"127.0.0.1:53948","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-08-19T09:02:27.511838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.820135Z","time spent":"691.693166ms","remote":"127.0.0.1:41704","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-08-19T09:02:27.511715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.21971ms","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-08-19T09:02:27.513991Z","caller":"traceutil/trace.go:171","msg":"trace[2021017323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2202; }","duration":"791.517406ms","start":"2026-08-19T09:02:26.722458Z","end":"2026-08-19T09:02:27.513975Z","steps":["trace[2021017323] 'agreement among raft nodes before linearized reading' (duration: 788.532024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:02:27.514042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:02:26.72245Z","time spent":"791.577027ms","remote":"127.0.0.1:41704","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-08-19T09:02:54.147253Z","caller":"traceutil/trace.go:171","msg":"trace[1122445572] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"110.240168ms","start":"2026-08-19T09:02:54.036987Z","end":"2026-08-19T09:02:54.147227Z","steps":["trace[1122445572] 'process raft request' (duration: 59.701493ms)","trace[1122445572] 'compare' (duration: 50.403452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:03:36.327074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.167334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:03:36.327193Z","caller":"traceutil/trace.go:171","msg":"trace[1607293578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2674; }","duration":"211.327179ms","start":"2026-08-19T09:03:36.115844Z","end":"2026-08-19T09:03:36.327171Z","steps":["trace[1607293578] 'count revisions from in-memory index tree' (duration: 211.098491ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:08.255919Z","caller":"traceutil/trace.go:171","msg":"trace[143903972] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"181.241524ms","start":"2026-08-19T09:04:08.074651Z","end":"2026-08-19T09:04:08.255893Z","steps":["trace[143903972] 'process raft request' (duration: 181.060608ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:08.952301Z","caller":"traceutil/trace.go:171","msg":"trace[1268318412] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"873.829414ms","start":"2026-08-19T09:04:08.078442Z","end":"2026-08-19T09:04:08.952271Z","steps":["trace[1268318412] 'process raft request' (duration: 839.898956ms)","trace[1268318412] 'compare' (duration: 32.77822ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:08.952358Z","caller":"traceutil/trace.go:171","msg":"trace[112141419] linearizableReadLoop","detail":"{readStateIndex:2960; appliedIndex:2958; }","duration":"814.683269ms","start":"2026-08-19T09:04:08.137658Z","end":"2026-08-19T09:04:08.952341Z","steps":["trace[112141419] 'read index received' (duration: 118.118231ms)","trace[112141419] 'applied index is now lower than readState.Index' (duration: 696.563898ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:08.952445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.078424Z","time spent":"873.950317ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:04:08.954717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.039626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cd297825576f04\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-19T09:04:08.954781Z","caller":"traceutil/trace.go:171","msg":"trace[1564789686] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cd297825576f04; range_end:; response_count:1; response_revision:2849; }","duration":"817.152489ms","start":"2026-08-19T09:04:08.137609Z","end":"2026-08-19T09:04:08.954762Z","steps":["trace[1564789686] 'agreement among raft nodes before linearized reading' (duration: 814.826474ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:08.954806Z","caller":"traceutil/trace.go:171","msg":"trace[1517637894] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"511.017025ms","start":"2026-08-19T09:04:08.443778Z","end":"2026-08-19T09:04:08.954795Z","steps":["trace[1517637894] 'process raft request' (duration: 510.58031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.954838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.137587Z","time spent":"817.235372ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":801,"request content":"key:\"/registry/events/auth-system/keycloak-0.18cd297825576f04\" "} {"level":"warn","ts":"2026-08-19T09:04:08.954875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.443759Z","time spent":"511.071926ms","remote":"127.0.0.1:41704","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-08-19T09:04:08.955118Z","caller":"traceutil/trace.go:171","msg":"trace[1700117677] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"510.669893ms","start":"2026-08-19T09:04:08.44444Z","end":"2026-08-19T09:04:08.95511Z","steps":["trace[1700117677] 'process raft request' (duration: 510.323492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.444425Z","time spent":"510.715245ms","remote":"127.0.0.1:41704","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-08-19T09:04:08.955306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.160505ms","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-08-19T09:04:08.955328Z","caller":"traceutil/trace.go:171","msg":"trace[1417281284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2852; }","duration":"815.198957ms","start":"2026-08-19T09:04:08.140124Z","end":"2026-08-19T09:04:08.955323Z","steps":["trace[1417281284] 'agreement among raft nodes before linearized reading' (duration: 815.119214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.140116Z","time spent":"815.225457ms","remote":"127.0.0.1:41704","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":"info","ts":"2026-08-19T09:04:08.955477Z","caller":"traceutil/trace.go:171","msg":"trace[1055218224] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"180.295583ms","start":"2026-08-19T09:04:08.775175Z","end":"2026-08-19T09:04:08.955471Z","steps":["trace[1055218224] 'process raft request' (duration: 179.89199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.484654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-19T09:04:08.955607Z","caller":"traceutil/trace.go:171","msg":"trace[1972029970] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2852; }","duration":"114.539696ms","start":"2026-08-19T09:04:08.841062Z","end":"2026-08-19T09:04:08.955602Z","steps":["trace[1972029970] 'agreement among raft nodes before linearized reading' (duration: 114.483684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.930614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:04:08.95574Z","caller":"traceutil/trace.go:171","msg":"trace[692619277] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2852; }","duration":"698.969896ms","start":"2026-08-19T09:04:08.256763Z","end":"2026-08-19T09:04:08.955733Z","steps":["trace[692619277] 'agreement among raft nodes before linearized reading' (duration: 698.924434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.746983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:04:08.955806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.910871ms","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-08-19T09:04:08.955804Z","caller":"traceutil/trace.go:171","msg":"trace[262136323] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2852; }","duration":"581.870267ms","start":"2026-08-19T09:04:08.373917Z","end":"2026-08-19T09:04:08.955787Z","steps":["trace[262136323] 'agreement among raft nodes before linearized reading' (duration: 581.717932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.5606ms","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-08-19T09:04:08.955825Z","caller":"traceutil/trace.go:171","msg":"trace[934050220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2852; }","duration":"752.992874ms","start":"2026-08-19T09:04:08.202828Z","end":"2026-08-19T09:04:08.955821Z","steps":["trace[934050220] 'agreement among raft nodes before linearized reading' (duration: 752.944332ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:08.955884Z","caller":"traceutil/trace.go:171","msg":"trace[552567120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2852; }","duration":"765.622043ms","start":"2026-08-19T09:04:08.190258Z","end":"2026-08-19T09:04:08.95588Z","steps":["trace[552567120] 'agreement among raft nodes before linearized reading' (duration: 765.578031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.9559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.190233Z","time spent":"765.663173ms","remote":"127.0.0.1:41704","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-08-19T09:04:08.955937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.163343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:04:08.955979Z","caller":"traceutil/trace.go:171","msg":"trace[1547137173] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2852; }","duration":"190.214754ms","start":"2026-08-19T09:04:08.765752Z","end":"2026-08-19T09:04:08.955966Z","steps":["trace[1547137173] 'agreement among raft nodes before linearized reading' (duration: 190.159453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.373896Z","time spent":"581.946229ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:04:08.955895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.202803Z","time spent":"753.083157ms","remote":"127.0.0.1:41704","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-08-19T09:04:08.956201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.422017ms","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-08-19T09:04:08.956246Z","caller":"traceutil/trace.go:171","msg":"trace[1006932619] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2852; }","duration":"205.53227ms","start":"2026-08-19T09:04:08.750705Z","end":"2026-08-19T09:04:08.956237Z","steps":["trace[1006932619] 'agreement among raft nodes before linearized reading' (duration: 205.461448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.955761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.256752Z","time spent":"699.004147ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:04:08.956637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.19897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-19T09:04:08.956666Z","caller":"traceutil/trace.go:171","msg":"trace[1093385536] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2852; }","duration":"405.283193ms","start":"2026-08-19T09:04:08.551375Z","end":"2026-08-19T09:04:08.956658Z","steps":["trace[1093385536] 'agreement among raft nodes before linearized reading' (duration: 405.20999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.956684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.551354Z","time spent":"405.325494ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-19T09:04:08.956862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.668069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:04:08.956918Z","caller":"traceutil/trace.go:171","msg":"trace[202921062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2852; }","duration":"453.765512ms","start":"2026-08-19T09:04:08.503141Z","end":"2026-08-19T09:04:08.956906Z","steps":["trace[202921062] 'agreement among raft nodes before linearized reading' (duration: 453.680489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:08.956956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:08.503125Z","time spent":"453.822494ms","remote":"127.0.0.1:41476","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-08-19T09:04:08.956422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.298993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:04:08.957551Z","caller":"traceutil/trace.go:171","msg":"trace[1331755422] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2852; }","duration":"278.493511ms","start":"2026-08-19T09:04:08.679045Z","end":"2026-08-19T09:04:08.957538Z","steps":["trace[1331755422] 'agreement among raft nodes before linearized reading' (duration: 277.342974ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:26.483396Z","caller":"traceutil/trace.go:171","msg":"trace[969518265] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"110.884707ms","start":"2026-08-19T09:04:26.372487Z","end":"2026-08-19T09:04:26.483372Z","steps":["trace[969518265] 'process raft request' (duration: 110.725762ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:50.544879Z","caller":"traceutil/trace.go:171","msg":"trace[25681853] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4812; }","duration":"150.021723ms","start":"2026-08-19T09:07:50.39484Z","end":"2026-08-19T09:07:50.544862Z","steps":["trace[25681853] 'read index received' (duration: 149.859417ms)","trace[25681853] 'applied index is now lower than readState.Index' (duration: 161.716µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:50.544975Z","caller":"traceutil/trace.go:171","msg":"trace[1203407920] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"152.288249ms","start":"2026-08-19T09:07:50.392678Z","end":"2026-08-19T09:07:50.544967Z","steps":["trace[1203407920] 'process raft request' (duration: 152.064021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:50.54521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.340335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:07:50.545275Z","caller":"traceutil/trace.go:171","msg":"trace[432800826] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4633; }","duration":"150.439399ms","start":"2026-08-19T09:07:50.394821Z","end":"2026-08-19T09:07:50.54526Z","steps":["trace[432800826] 'agreement among raft nodes before linearized reading' (duration: 150.305134ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:56.345252Z","caller":"traceutil/trace.go:171","msg":"trace[748689345] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"124.369647ms","start":"2026-08-19T09:07:56.220844Z","end":"2026-08-19T09:07:56.345213Z","steps":["trace[748689345] 'process raft request' (duration: 122.680543ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:02.551971Z","caller":"traceutil/trace.go:171","msg":"trace[1795562733] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"337.153332ms","start":"2026-08-19T09:08:02.214788Z","end":"2026-08-19T09:08:02.551941Z","steps":["trace[1795562733] 'process raft request' (duration: 336.873181ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:02.551976Z","caller":"traceutil/trace.go:171","msg":"trace[1377340550] linearizableReadLoop","detail":"{readStateIndex:4973; appliedIndex:4972; }","duration":"336.247727ms","start":"2026-08-19T09:08:02.215672Z","end":"2026-08-19T09:08:02.55192Z","steps":["trace[1377340550] 'read index received' (duration: 335.933595ms)","trace[1377340550] 'applied index is now lower than readState.Index' (duration: 312.712µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:02.552129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:02.214756Z","time spent":"337.296797ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:02.55239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.696875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:08:02.552548Z","caller":"traceutil/trace.go:171","msg":"trace[268991744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4787; }","duration":"336.828559ms","start":"2026-08-19T09:08:02.215628Z","end":"2026-08-19T09:08:02.552457Z","steps":["trace[268991744] 'agreement among raft nodes before linearized reading' (duration: 336.409453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:02.552607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:02.21561Z","time spent":"336.982696ms","remote":"127.0.0.1:41704","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-08-19T09:08:02.616568Z","caller":"traceutil/trace.go:171","msg":"trace[441615196] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"254.926303ms","start":"2026-08-19T09:08:02.361624Z","end":"2026-08-19T09:08:02.61655Z","steps":["trace[441615196] 'process raft request' (duration: 254.819019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:02.616745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.285742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:02.616818Z","caller":"traceutil/trace.go:171","msg":"trace[237004724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4788; }","duration":"113.402286ms","start":"2026-08-19T09:08:02.503399Z","end":"2026-08-19T09:08:02.616801Z","steps":["trace[237004724] 'agreement among raft nodes before linearized reading' (duration: 113.23501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:02.61699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.936188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:08:02.61704Z","caller":"traceutil/trace.go:171","msg":"trace[1043982422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4788; }","duration":"205.999741ms","start":"2026-08-19T09:08:02.411029Z","end":"2026-08-19T09:08:02.617029Z","steps":["trace[1043982422] 'agreement among raft nodes before linearized reading' (duration: 205.910348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:02.617135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.995672ms","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-08-19T09:08:02.61718Z","caller":"traceutil/trace.go:171","msg":"trace[805958871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4788; }","duration":"396.052693ms","start":"2026-08-19T09:08:02.221118Z","end":"2026-08-19T09:08:02.61717Z","steps":["trace[805958871] 'agreement among raft nodes before linearized reading' (duration: 395.919778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:02.617211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:02.22111Z","time spent":"396.090706ms","remote":"127.0.0.1:41704","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-08-19T09:08:02.950335Z","caller":"traceutil/trace.go:171","msg":"trace[2140959702] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"109.906082ms","start":"2026-08-19T09:08:02.840399Z","end":"2026-08-19T09:08:02.950305Z","steps":["trace[2140959702] 'process raft request' (duration: 81.417488ms)","trace[2140959702] 'compare' (duration: 28.3739ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:02.968562Z","caller":"traceutil/trace.go:171","msg":"trace[837336517] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"122.474541ms","start":"2026-08-19T09:08:02.845876Z","end":"2026-08-19T09:08:02.968351Z","steps":["trace[837336517] 'process raft request' (duration: 121.753894ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.209879Z","caller":"traceutil/trace.go:171","msg":"trace[1095636008] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"113.322744ms","start":"2026-08-19T09:08:03.096532Z","end":"2026-08-19T09:08:03.209855Z","steps":["trace[1095636008] 'process raft request' (duration: 113.281863ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.209958Z","caller":"traceutil/trace.go:171","msg":"trace[1020810388] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"114.838871ms","start":"2026-08-19T09:08:03.095089Z","end":"2026-08-19T09:08:03.209928Z","steps":["trace[1020810388] 'process raft request' (duration: 114.54896ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.210158Z","caller":"traceutil/trace.go:171","msg":"trace[1367982014] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"113.809373ms","start":"2026-08-19T09:08:03.096316Z","end":"2026-08-19T09:08:03.210125Z","steps":["trace[1367982014] 'process raft request' (duration: 113.443239ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.389022Z","caller":"traceutil/trace.go:171","msg":"trace[1332616141] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"136.59713ms","start":"2026-08-19T09:08:03.252401Z","end":"2026-08-19T09:08:03.388998Z","steps":["trace[1332616141] 'process raft request' (duration: 135.214408ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.389016Z","caller":"traceutil/trace.go:171","msg":"trace[1288949193] linearizableReadLoop","detail":"{readStateIndex:4992; appliedIndex:4991; }","duration":"129.797371ms","start":"2026-08-19T09:08:03.259196Z","end":"2026-08-19T09:08:03.388993Z","steps":["trace[1288949193] 'read index received' (duration: 128.436739ms)","trace[1288949193] 'applied index is now lower than readState.Index' (duration: 1.359762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:03.389218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.006129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-v285s\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-08-19T09:08:03.389255Z","caller":"traceutil/trace.go:171","msg":"trace[1703813991] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-v285s; range_end:; response_count:1; response_revision:4805; }","duration":"130.082592ms","start":"2026-08-19T09:08:03.259162Z","end":"2026-08-19T09:08:03.389245Z","steps":["trace[1703813991] 'agreement among raft nodes before linearized reading' (duration: 129.901225ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:04.968349Z","caller":"traceutil/trace.go:171","msg":"trace[1174112946] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"189.609729ms","start":"2026-08-19T09:08:04.778704Z","end":"2026-08-19T09:08:04.968314Z","steps":["trace[1174112946] 'process raft request' (duration: 189.398351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:16.469053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.299914ms","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-08-19T09:08:16.46913Z","caller":"traceutil/trace.go:171","msg":"trace[769185182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5009; }","duration":"223.419739ms","start":"2026-08-19T09:08:16.245695Z","end":"2026-08-19T09:08:16.469115Z","steps":["trace[769185182] 'range keys from in-memory index tree' (duration: 222.999813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.183343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845538080278330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:08:17.489919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.322843ms","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-08-19T09:08:17.489954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.424829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:08:17.489996Z","caller":"traceutil/trace.go:171","msg":"trace[1790504600] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5011; }","duration":"892.503262ms","start":"2026-08-19T09:08:16.59748Z","end":"2026-08-19T09:08:17.489984Z","steps":["trace[1790504600] 'count revisions from in-memory index tree' (duration: 892.338396ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:17.489995Z","caller":"traceutil/trace.go:171","msg":"trace[45191521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5011; }","duration":"882.428477ms","start":"2026-08-19T09:08:16.60755Z","end":"2026-08-19T09:08:17.489979Z","steps":["trace[45191521] 'range keys from in-memory index tree' (duration: 882.179227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.490025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.597464Z","time spent":"892.553594ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:08:17.490029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.607539Z","time spent":"882.481749ms","remote":"127.0.0.1:41704","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-08-19T09:08:17.494192Z","caller":"traceutil/trace.go:171","msg":"trace[155398263] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"830.174052ms","start":"2026-08-19T09:08:16.663995Z","end":"2026-08-19T09:08:17.49417Z","steps":["trace[155398263] 'process raft request' (duration: 830.044297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.494326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.663968Z","time spent":"830.287607ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:17.49606Z","caller":"traceutil/trace.go:171","msg":"trace[454327726] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"813.222546ms","start":"2026-08-19T09:08:16.682815Z","end":"2026-08-19T09:08:17.496037Z","steps":["trace[454327726] 'read index received' (duration: 811.406606ms)","trace[454327726] 'applied index is now lower than readState.Index' (duration: 1.81498ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:17.496289Z","caller":"traceutil/trace.go:171","msg":"trace[1648972708] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"492.197052ms","start":"2026-08-19T09:08:17.00408Z","end":"2026-08-19T09:08:17.496277Z","steps":["trace[1648972708] 'process raft request' (duration: 491.716003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.496386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:17.004062Z","time spent":"492.259925ms","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:17.496622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.806528ms","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-08-19T09:08:17.496674Z","caller":"traceutil/trace.go:171","msg":"trace[395037905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5013; }","duration":"813.887381ms","start":"2026-08-19T09:08:16.682776Z","end":"2026-08-19T09:08:17.496664Z","steps":["trace[395037905] 'agreement among raft nodes before linearized reading' (duration: 813.748455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.496836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.682763Z","time spent":"813.936453ms","remote":"127.0.0.1:41704","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-08-19T09:08:17.497731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.822731ms","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-08-19T09:08:17.497786Z","caller":"traceutil/trace.go:171","msg":"trace[1596284951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5014; }","duration":"812.917524ms","start":"2026-08-19T09:08:16.684856Z","end":"2026-08-19T09:08:17.497773Z","steps":["trace[1596284951] 'agreement among raft nodes before linearized reading' (duration: 812.766918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.497816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.684837Z","time spent":"812.973946ms","remote":"127.0.0.1:41704","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-08-19T09:08:17.498136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.294292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:17.498178Z","caller":"traceutil/trace.go:171","msg":"trace[182665842] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5014; }","duration":"181.339344ms","start":"2026-08-19T09:08:17.316828Z","end":"2026-08-19T09:08:17.498167Z","steps":["trace[182665842] 'agreement among raft nodes before linearized reading' (duration: 181.274651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.49869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.443479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s6gkz.18cd29b2f8150067\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-19T09:08:17.498731Z","caller":"traceutil/trace.go:171","msg":"trace[346805368] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s6gkz.18cd29b2f8150067; range_end:; response_count:1; response_revision:5014; }","duration":"109.520081ms","start":"2026-08-19T09:08:17.389203Z","end":"2026-08-19T09:08:17.498723Z","steps":["trace[346805368] 'agreement among raft nodes before linearized reading' (duration: 109.407607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.498734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.790907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e6b8773-66e9-4b6b-9dcd-4bab602665ca\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-19T09:08:17.49877Z","caller":"traceutil/trace.go:171","msg":"trace[1455152908] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e6b8773-66e9-4b6b-9dcd-4bab602665ca; range_end:; response_count:1; response_revision:5014; }","duration":"545.855349ms","start":"2026-08-19T09:08:16.952904Z","end":"2026-08-19T09:08:17.498759Z","steps":["trace[1455152908] 'agreement among raft nodes before linearized reading' (duration: 545.731045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.498797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:16.952888Z","time spent":"545.903921ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e6b8773-66e9-4b6b-9dcd-4bab602665ca\" "} {"level":"info","ts":"2026-08-19T09:08:17.605464Z","caller":"traceutil/trace.go:171","msg":"trace[182317708] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"103.788933ms","start":"2026-08-19T09:08:17.501647Z","end":"2026-08-19T09:08:17.605436Z","steps":["trace[182317708] 'process raft request' (duration: 99.687626ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:17.607174Z","caller":"traceutil/trace.go:171","msg":"trace[268322253] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"104.477869ms","start":"2026-08-19T09:08:17.502674Z","end":"2026-08-19T09:08:17.607151Z","steps":["trace[268322253] 'process raft request' (duration: 104.316103ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:17.607554Z","caller":"traceutil/trace.go:171","msg":"trace[1169823651] linearizableReadLoop","detail":"{readStateIndex:5209; appliedIndex:5206; }","duration":"102.461452ms","start":"2026-08-19T09:08:17.505061Z","end":"2026-08-19T09:08:17.607522Z","steps":["trace[1169823651] 'read index received' (duration: 96.264486ms)","trace[1169823651] 'applied index is now lower than readState.Index' (duration: 6.194066ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:17.6076Z","caller":"traceutil/trace.go:171","msg":"trace[1769061132] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"102.622528ms","start":"2026-08-19T09:08:17.504948Z","end":"2026-08-19T09:08:17.607571Z","steps":["trace[1769061132] 'process raft request' (duration: 102.16044ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:17.607675Z","caller":"traceutil/trace.go:171","msg":"trace[2128315598] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"102.563935ms","start":"2026-08-19T09:08:17.505096Z","end":"2026-08-19T09:08:17.60766Z","steps":["trace[2128315598] 'process raft request' (duration: 102.178741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:17.607749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.66534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:17.60779Z","caller":"traceutil/trace.go:171","msg":"trace[1888053390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5018; }","duration":"102.744163ms","start":"2026-08-19T09:08:17.505036Z","end":"2026-08-19T09:08:17.60778Z","steps":["trace[1888053390] 'agreement among raft nodes before linearized reading' (duration: 102.650039ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:24.924237Z","caller":"traceutil/trace.go:171","msg":"trace[526388157] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"128.105757ms","start":"2026-08-19T09:08:24.79611Z","end":"2026-08-19T09:08:24.924215Z","steps":["trace[526388157] 'process raft request' (duration: 127.940841ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:29.668335Z","caller":"traceutil/trace.go:171","msg":"trace[1087564078] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"112.573698ms","start":"2026-08-19T09:08:29.555724Z","end":"2026-08-19T09:08:29.668298Z","steps":["trace[1087564078] 'process raft request' (duration: 77.306529ms)","trace[1087564078] 'compare' (duration: 35.078942ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:00.036341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2808} {"level":"info","ts":"2026-08-19T09:09:00.143536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2808,"took":"105.936043ms","hash":3313786304,"current-db-size-bytes":52150272,"current-db-size":"52 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-19T09:09:00.143592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3313786304,"revision":2808,"compact-revision":-1} {"level":"warn","ts":"2026-08-19T09:09:07.555621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.046654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-08-19T09:09:07.555708Z","caller":"traceutil/trace.go:171","msg":"trace[157322174] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5434; }","duration":"178.166898ms","start":"2026-08-19T09:09:07.377518Z","end":"2026-08-19T09:09:07.555685Z","steps":["trace[157322174] 'range keys from in-memory index tree' (duration: 177.913729ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:08.141335Z","caller":"traceutil/trace.go:171","msg":"trace[773194263] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"102.537348ms","start":"2026-08-19T09:09:08.03877Z","end":"2026-08-19T09:09:08.141307Z","steps":["trace[773194263] 'process raft request' (duration: 102.372742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:08.162363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.245486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:08.16244Z","caller":"traceutil/trace.go:171","msg":"trace[568852447] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5443; }","duration":"102.391591ms","start":"2026-08-19T09:09:08.060031Z","end":"2026-08-19T09:09:08.162423Z","steps":["trace[568852447] 'agreement among raft nodes before linearized reading' (duration: 102.228245ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:48.332922Z","caller":"traceutil/trace.go:171","msg":"trace[1990433764] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"197.96727ms","start":"2026-08-19T09:10:48.134905Z","end":"2026-08-19T09:10:48.332872Z","steps":["trace[1990433764] 'process raft request' (duration: 195.262665ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:48.33297Z","caller":"traceutil/trace.go:171","msg":"trace[1154307036] transaction","detail":"{read_only:false; response_revision:6080; number_of_response:1; }","duration":"192.992117ms","start":"2026-08-19T09:10:48.139953Z","end":"2026-08-19T09:10:48.332945Z","steps":["trace[1154307036] 'process raft request' (duration: 192.819751ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:48.551985Z","caller":"traceutil/trace.go:171","msg":"trace[1178458140] transaction","detail":"{read_only:false; response_revision:6082; number_of_response:1; }","duration":"101.768454ms","start":"2026-08-19T09:10:48.450193Z","end":"2026-08-19T09:10:48.551962Z","steps":["trace[1178458140] 'process raft request' (duration: 101.615528ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:48.679872Z","caller":"traceutil/trace.go:171","msg":"trace[650308069] transaction","detail":"{read_only:false; response_revision:6083; number_of_response:1; }","duration":"229.672482ms","start":"2026-08-19T09:10:48.450158Z","end":"2026-08-19T09:10:48.679831Z","steps":["trace[650308069] 'process raft request' (duration: 208.500949ms)","trace[650308069] 'compare' (duration: 20.931054ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:48.679976Z","caller":"traceutil/trace.go:171","msg":"trace[1580040075] transaction","detail":"{read_only:false; response_revision:6084; number_of_response:1; }","duration":"220.675293ms","start":"2026-08-19T09:10:48.459275Z","end":"2026-08-19T09:10:48.67995Z","steps":["trace[1580040075] 'process raft request' (duration: 220.471235ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:48.679879Z","caller":"traceutil/trace.go:171","msg":"trace[69788512] linearizableReadLoop","detail":"{readStateIndex:6311; appliedIndex:6307; }","duration":"208.786391ms","start":"2026-08-19T09:10:48.471075Z","end":"2026-08-19T09:10:48.679861Z","steps":["trace[69788512] 'read index received' (duration: 80.780878ms)","trace[69788512] 'applied index is now lower than readState.Index' (duration: 128.004463ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:10:48.680037Z","caller":"traceutil/trace.go:171","msg":"trace[1764223247] transaction","detail":"{read_only:false; response_revision:6085; number_of_response:1; }","duration":"217.550862ms","start":"2026-08-19T09:10:48.462432Z","end":"2026-08-19T09:10:48.679983Z","steps":["trace[1764223247] 'process raft request' (duration: 217.358815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:10:48.680184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.091873ms","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-08-19T09:10:48.680316Z","caller":"traceutil/trace.go:171","msg":"trace[591718510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6085; }","duration":"209.25649ms","start":"2026-08-19T09:10:48.471045Z","end":"2026-08-19T09:10:48.680302Z","steps":["trace[591718510] 'agreement among raft nodes before linearized reading' (duration: 209.026881ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:24.789828Z","caller":"traceutil/trace.go:171","msg":"trace[878089145] transaction","detail":"{read_only:false; response_revision:6828; number_of_response:1; }","duration":"103.24782ms","start":"2026-08-19T09:12:24.686561Z","end":"2026-08-19T09:12:24.789809Z","steps":["trace[878089145] 'process raft request' (duration: 103.146408ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:37.209596Z","caller":"traceutil/trace.go:171","msg":"trace[1603571714] transaction","detail":"{read_only:false; response_revision:6891; number_of_response:1; }","duration":"124.372396ms","start":"2026-08-19T09:12:37.085197Z","end":"2026-08-19T09:12:37.209569Z","steps":["trace[1603571714] 'process raft request' (duration: 123.632339ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:00.04178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5395} {"level":"info","ts":"2026-08-19T09:14:00.145079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5395,"took":"102.116369ms","hash":95820282,"current-db-size-bytes":52150272,"current-db-size":"52 MB","current-db-size-in-use-bytes":18661376,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-19T09:14:00.14513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95820282,"revision":5395,"compact-revision":2808}