{"level":"warn","ts":"2026-07-25T06:50:47.15361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T06:50:47.157586Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.126: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.19.213.126:2380","--initial-cluster=instance=https://199.19.213.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.126: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-07-25T06:50:47.157738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T06:50:47.157784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-07-25T06:50:47.157979Z","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-07-25T06:50:47.159214Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"]} {"level":"info","ts":"2026-07-25T06:50:47.159386Z","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.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.126: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-07-25T06:50:47.180011Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"20.300879ms"} {"level":"info","ts":"2026-07-25T06:50:47.198054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e241c9714ab70808","cluster-id":"e3245fbcce84a6b1"} {"level":"info","ts":"2026-07-25T06:50:47.198169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T06:50:47.198247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 0"} {"level":"info","ts":"2026-07-25T06:50:47.198308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e241c9714ab70808 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T06:50:47.198318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 1"} {"level":"info","ts":"2026-07-25T06:50:47.198381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"warn","ts":"2026-07-25T06:50:47.215823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T06:50:47.217396Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T06:50:47.219266Z","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-07-25T06:50:47.22249Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e241c9714ab70808","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T06:50:47.222685Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e241c9714ab70808","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T06:50:47.222914Z","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-07-25T06:50:47.222967Z","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-07-25T06:50:47.222978Z","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-07-25T06:50:47.230945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"info","ts":"2026-07-25T06:50:47.231287Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","added-peer-id":"e241c9714ab70808","added-peer-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-07-25T06:50:47.233445Z","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-07-25T06:50:47.233772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-07-25T06:50:47.233758Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e241c9714ab70808","initial-advertise-peer-urls":["https://199.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T06:50:47.233815Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-07-25T06:50:47.233841Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T06:50:48.098941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T06:50:48.098988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T06:50:48.099023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgPreVoteResp from e241c9714ab70808 at term 1"} {"level":"info","ts":"2026-07-25T06:50:48.099035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became candidate at term 2"} {"level":"info","ts":"2026-07-25T06:50:48.099046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgVoteResp from e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-07-25T06:50:48.099055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became leader at term 2"} {"level":"info","ts":"2026-07-25T06:50:48.099063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e241c9714ab70808 elected leader e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-07-25T06:50:48.100017Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e241c9714ab70808","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.126:2379]}","request-path":"/0/members/e241c9714ab70808/attributes","cluster-id":"e3245fbcce84a6b1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T06:50:48.100025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T06:50:48.100112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T06:50:48.100209Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T06:50:48.100258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T06:50:48.100405Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T06:50:48.103379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.126:2379"} {"level":"info","ts":"2026-07-25T06:50:48.103747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T06:50:48.104345Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T06:50:48.104474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T06:50:48.104516Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-25T06:50:54.406102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.340557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-25T06:50:54.406474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.282244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-25T06:50:54.406223Z","caller":"traceutil/trace.go:171","msg":"trace[1801950013] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"679.723811ms","start":"2026-07-25T06:50:53.726473Z","end":"2026-07-25T06:50:54.406197Z","steps":["trace[1801950013] 'process raft request' (duration: 337.558261ms)","trace[1801950013] 'compare' (duration: 341.205792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:50:54.423016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.726457Z","time spent":"696.498156ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:50:54.406562Z","caller":"traceutil/trace.go:171","msg":"trace[2097004550] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"653.440989ms","start":"2026-07-25T06:50:53.753099Z","end":"2026-07-25T06:50:54.40654Z","steps":["trace[2097004550] 'agreement among raft nodes before linearized reading' (duration: 653.222902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:54.423122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.753073Z","time spent":"670.027398ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-25T06:50:54.4062Z","caller":"traceutil/trace.go:171","msg":"trace[1720751014] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"653.041187ms","start":"2026-07-25T06:50:53.753147Z","end":"2026-07-25T06:50:54.406188Z","steps":["trace[1720751014] 'read index received' (duration: 310.901387ms)","trace[1720751014] 'applied index is now lower than readState.Index' (duration: 342.13896ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:50:54.485728Z","caller":"traceutil/trace.go:171","msg":"trace[1267308619] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"580.815494ms","start":"2026-07-25T06:50:53.904893Z","end":"2026-07-25T06:50:54.485709Z","steps":["trace[1267308619] 'process raft request' (duration: 580.659429ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:54.485785Z","caller":"traceutil/trace.go:171","msg":"trace[1565968618] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"580.625327ms","start":"2026-07-25T06:50:53.905137Z","end":"2026-07-25T06:50:54.485763Z","steps":["trace[1565968618] 'process raft request' (duration: 580.517274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:54.485903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.905112Z","time spent":"580.726761ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:50:54.485893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.006379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"warn","ts":"2026-07-25T06:50:54.485924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.904867Z","time spent":"580.963868ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:50:54.485963Z","caller":"traceutil/trace.go:171","msg":"trace[1689094626] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:315; }","duration":"581.135613ms","start":"2026-07-25T06:50:53.904813Z","end":"2026-07-25T06:50:54.485949Z","steps":["trace[1689094626] 'agreement among raft nodes before linearized reading' (duration: 580.971578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:54.485997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.904764Z","time spent":"581.228376ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5921,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-07-25T06:50:54.486043Z","caller":"traceutil/trace.go:171","msg":"trace[1682383202] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"581.082551ms","start":"2026-07-25T06:50:53.904949Z","end":"2026-07-25T06:50:54.486032Z","steps":["trace[1682383202] 'process raft request' (duration: 580.681959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:54.486137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:53.904921Z","time spent":"581.195015ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:50:55.159469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.743821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:50:55.159641Z","caller":"traceutil/trace.go:171","msg":"trace[1877773998] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"673.945704ms","start":"2026-07-25T06:50:54.485679Z","end":"2026-07-25T06:50:55.159625Z","steps":["trace[1877773998] 'read index received' (duration: 379.986807ms)","trace[1877773998] 'applied index is now lower than readState.Index' (duration: 293.958037ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:50:55.159712Z","caller":"traceutil/trace.go:171","msg":"trace[2010765678] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"719.081317ms","start":"2026-07-25T06:50:54.440622Z","end":"2026-07-25T06:50:55.159704Z","steps":["trace[2010765678] 'process raft request' (duration: 718.944793ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:55.159712Z","caller":"traceutil/trace.go:171","msg":"trace[718935984] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"719.740816ms","start":"2026-07-25T06:50:54.439948Z","end":"2026-07-25T06:50:55.159688Z","steps":["trace[718935984] 'process raft request' (duration: 425.708596ms)","trace[718935984] 'compare' (duration: 293.606307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:50:55.159779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.440605Z","time spent":"719.141268ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:50:55.159791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.439929Z","time spent":"719.827779ms","remote":"127.0.0.1:44144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:50:55.159883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.624872ms","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-07-25T06:50:55.15997Z","caller":"traceutil/trace.go:171","msg":"trace[296826535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"720.744185ms","start":"2026-07-25T06:50:54.439207Z","end":"2026-07-25T06:50:55.159951Z","steps":["trace[296826535] 'agreement among raft nodes before linearized reading' (duration: 720.506698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.160017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.439145Z","time spent":"720.862649ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T06:50:55.187051Z","caller":"traceutil/trace.go:171","msg":"trace[1724810422] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"679.849092ms","start":"2026-07-25T06:50:54.507182Z","end":"2026-07-25T06:50:55.187031Z","steps":["trace[1724810422] 'process raft request' (duration: 679.593084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.187106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.162711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-07-25T06:50:55.187179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.260374ms","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-07-25T06:50:55.187224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.202892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-25T06:50:55.18726Z","caller":"traceutil/trace.go:171","msg":"trace[1291150537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"240.383668ms","start":"2026-07-25T06:50:54.946857Z","end":"2026-07-25T06:50:55.187241Z","steps":["trace[1291150537] 'agreement among raft nodes before linearized reading' (duration: 240.211843ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:55.187278Z","caller":"traceutil/trace.go:171","msg":"trace[124498792] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:318; }","duration":"700.343046ms","start":"2026-07-25T06:50:54.486886Z","end":"2026-07-25T06:50:55.187229Z","steps":["trace[124498792] 'agreement among raft nodes before linearized reading' (duration: 700.11376ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:55.187308Z","caller":"traceutil/trace.go:171","msg":"trace[751611373] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:318; }","duration":"700.321115ms","start":"2026-07-25T06:50:54.486967Z","end":"2026-07-25T06:50:55.187288Z","steps":["trace[751611373] 'agreement among raft nodes before linearized reading' (duration: 700.030577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.187367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.486954Z","time spent":"700.403948ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-25T06:50:55.187368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.486876Z","time spent":"700.47822ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7135,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-25T06:50:55.187263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.507166Z","time spent":"679.934934ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:50:55.187427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.518221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-07-25T06:50:55.187596Z","caller":"traceutil/trace.go:171","msg":"trace[2089473351] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:318; }","duration":"700.749197ms","start":"2026-07-25T06:50:54.486825Z","end":"2026-07-25T06:50:55.187574Z","steps":["trace[2089473351] 'agreement among raft nodes before linearized reading' (duration: 700.361556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.187676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:54.486814Z","time spent":"700.849411ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4004,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-07-25T06:50:55.544266Z","caller":"traceutil/trace.go:171","msg":"trace[854185136] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"357.266912ms","start":"2026-07-25T06:50:55.186967Z","end":"2026-07-25T06:50:55.544234Z","steps":["trace[854185136] 'read index received' (duration: 342.51146ms)","trace[854185136] 'applied index is now lower than readState.Index' (duration: 14.753662ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:50:55.544317Z","caller":"traceutil/trace.go:171","msg":"trace[327606373] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"379.486692ms","start":"2026-07-25T06:50:55.164792Z","end":"2026-07-25T06:50:55.544279Z","steps":["trace[327606373] 'process raft request' (duration: 364.674639ms)","trace[327606373] 'compare' (duration: 14.460183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:50:55.544488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:55.164772Z","time spent":"379.622266ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:50:55.544654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.811461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:50:55.54467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.230434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-07-25T06:50:55.544694Z","caller":"traceutil/trace.go:171","msg":"trace[84417074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:320; }","duration":"380.881153ms","start":"2026-07-25T06:50:55.163802Z","end":"2026-07-25T06:50:55.544683Z","steps":["trace[84417074] 'agreement among raft nodes before linearized reading' (duration: 380.648006ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:55.544712Z","caller":"traceutil/trace.go:171","msg":"trace[1296762380] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:320; }","duration":"353.362427ms","start":"2026-07-25T06:50:55.191337Z","end":"2026-07-25T06:50:55.5447Z","steps":["trace[1296762380] 'agreement among raft nodes before linearized reading' (duration: 353.174242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.54473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:55.163786Z","time spent":"380.938024ms","remote":"127.0.0.1:44144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-25T06:50:55.544756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:55.19132Z","time spent":"353.42492ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4004,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-25T06:50:55.545063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.766252ms","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-07-25T06:50:55.545104Z","caller":"traceutil/trace.go:171","msg":"trace[711526846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:320; }","duration":"176.841724ms","start":"2026-07-25T06:50:55.368252Z","end":"2026-07-25T06:50:55.545094Z","steps":["trace[711526846] 'agreement among raft nodes before linearized reading' (duration: 176.772812ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:50:55.545228Z","caller":"traceutil/trace.go:171","msg":"trace[889590946] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"352.186203ms","start":"2026-07-25T06:50:55.193026Z","end":"2026-07-25T06:50:55.545212Z","steps":["trace[889590946] 'process raft request' (duration: 351.157983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:50:55.545354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:50:55.193006Z","time spent":"352.257506ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:51:25.6151Z","caller":"traceutil/trace.go:171","msg":"trace[2024158417] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"387.843803ms","start":"2026-07-25T06:51:25.227221Z","end":"2026-07-25T06:51:25.615065Z","steps":["trace[2024158417] 'process raft request' (duration: 387.647699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.615376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.227189Z","time spent":"388.082559ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.621969Z","caller":"traceutil/trace.go:171","msg":"trace[1250377302] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"371.295474ms","start":"2026-07-25T06:51:25.250636Z","end":"2026-07-25T06:51:25.621931Z","steps":["trace[1250377302] 'process raft request' (duration: 370.70401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.622142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.250625Z","time spent":"371.440538ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.622381Z","caller":"traceutil/trace.go:171","msg":"trace[12954815] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"390.957605ms","start":"2026-07-25T06:51:25.231385Z","end":"2026-07-25T06:51:25.622343Z","steps":["trace[12954815] 'process raft request' (duration: 389.818829ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:25.62255Z","caller":"traceutil/trace.go:171","msg":"trace[1260288836] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"381.945339ms","start":"2026-07-25T06:51:25.240589Z","end":"2026-07-25T06:51:25.622534Z","steps":["trace[1260288836] 'process raft request' (duration: 380.659819ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:25.622579Z","caller":"traceutil/trace.go:171","msg":"trace[2130035953] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"364.932108ms","start":"2026-07-25T06:51:25.257632Z","end":"2026-07-25T06:51:25.622564Z","steps":["trace[2130035953] 'process raft request' (duration: 363.854384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.622606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.231349Z","time spent":"391.142889ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.62265Z","caller":"traceutil/trace.go:171","msg":"trace[1829049618] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"381.966569ms","start":"2026-07-25T06:51:25.240639Z","end":"2026-07-25T06:51:25.622605Z","steps":["trace[1829049618] 'process raft request' (duration: 380.661729ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:25.622379Z","caller":"traceutil/trace.go:171","msg":"trace[1993055688] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"371.52791ms","start":"2026-07-25T06:51:25.250784Z","end":"2026-07-25T06:51:25.622312Z","steps":["trace[1993055688] 'process raft request' (duration: 370.641949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.62273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.257625Z","time spent":"365.01977ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.62277Z","caller":"traceutil/trace.go:171","msg":"trace[1703534942] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"347.829967ms","start":"2026-07-25T06:51:25.274835Z","end":"2026-07-25T06:51:25.622665Z","steps":["trace[1703534942] 'process raft request' (duration: 346.709701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.622799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.250779Z","time spent":"371.99857ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:51:25.622618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.240579Z","time spent":"382.00826ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.622926Z","caller":"traceutil/trace.go:171","msg":"trace[1681427070] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"394.23263ms","start":"2026-07-25T06:51:25.228681Z","end":"2026-07-25T06:51:25.622913Z","steps":["trace[1681427070] 'process raft request' (duration: 392.46598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.622906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.274818Z","time spent":"348.00849ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:51:25.622931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.240634Z","time spent":"382.112333ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:51:25.623116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.228655Z","time spent":"394.303961ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.623126Z","caller":"traceutil/trace.go:171","msg":"trace[1360513387] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"326.913137ms","start":"2026-07-25T06:51:25.296193Z","end":"2026-07-25T06:51:25.623106Z","steps":["trace[1360513387] 'process raft request' (duration: 325.396782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.623222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.296179Z","time spent":"327.006319ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.622502Z","caller":"traceutil/trace.go:171","msg":"trace[701235999] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"371.799546ms","start":"2026-07-25T06:51:25.250691Z","end":"2026-07-25T06:51:25.62249Z","steps":["trace[701235999] 'process raft request' (duration: 370.69454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:25.623416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:25.250685Z","time spent":"372.684696ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:25.623809Z","caller":"traceutil/trace.go:171","msg":"trace[383255172] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"199.294209ms","start":"2026-07-25T06:51:25.424499Z","end":"2026-07-25T06:51:25.623793Z","steps":["trace[383255172] 'process raft request' (duration: 199.117805ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:29.258674Z","caller":"traceutil/trace.go:171","msg":"trace[259273667] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"438.091234ms","start":"2026-07-25T06:51:28.820188Z","end":"2026-07-25T06:51:29.258279Z","steps":["trace[259273667] 'process raft request' (duration: 437.90413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:29.258864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:28.82017Z","time spent":"438.613797ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T06:51:31.739465Z","caller":"traceutil/trace.go:171","msg":"trace[1029990937] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"102.753599ms","start":"2026-07-25T06:51:31.636682Z","end":"2026-07-25T06:51:31.739436Z","steps":["trace[1029990937] 'process raft request' (duration: 102.060644ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:33.182275Z","caller":"traceutil/trace.go:171","msg":"trace[78035429] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"169.221851ms","start":"2026-07-25T06:51:33.013017Z","end":"2026-07-25T06:51:33.182239Z","steps":["trace[78035429] 'read index received' (duration: 168.887134ms)","trace[78035429] 'applied index is now lower than readState.Index' (duration: 333.187µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:51:33.182465Z","caller":"traceutil/trace.go:171","msg":"trace[457664577] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"359.792771ms","start":"2026-07-25T06:51:32.822573Z","end":"2026-07-25T06:51:33.182365Z","steps":["trace[457664577] 'process raft request' (duration: 359.448743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:33.182648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.594729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tzhdc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T06:51:33.182694Z","caller":"traceutil/trace.go:171","msg":"trace[1641945356] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tzhdc; range_end:; response_count:1; response_revision:641; }","duration":"169.709772ms","start":"2026-07-25T06:51:33.01297Z","end":"2026-07-25T06:51:33.18268Z","steps":["trace[1641945356] 'agreement among raft nodes before linearized reading' (duration: 169.410475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:33.182742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:32.822554Z","time spent":"360.076288ms","remote":"127.0.0.1:44454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:51:34.407894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147899322s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:51:34.408124Z","caller":"traceutil/trace.go:171","msg":"trace[909719453] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"994.258869ms","start":"2026-07-25T06:51:33.413845Z","end":"2026-07-25T06:51:34.408103Z","steps":["trace[909719453] 'process raft request' (duration: 994.183297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.408218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.413808Z","time spent":"994.366791ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:51:34.408185Z","caller":"traceutil/trace.go:171","msg":"trace[1210242910] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.205634344s","start":"2026-07-25T06:51:33.20251Z","end":"2026-07-25T06:51:34.408144Z","steps":["trace[1210242910] 'process raft request' (duration: 57.371974ms)","trace[1210242910] 'compare' (duration: 1.147652116s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:51:34.408399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.20249Z","time spent":"1.205805988s","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:51:34.525107Z","caller":"traceutil/trace.go:171","msg":"trace[2032671522] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"811.633861ms","start":"2026-07-25T06:51:33.713435Z","end":"2026-07-25T06:51:34.525069Z","steps":["trace[2032671522] 'read index received' (duration: 250.986595ms)","trace[2032671522] 'applied index is now lower than readState.Index' (duration: 560.645956ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:51:34.525232Z","caller":"traceutil/trace.go:171","msg":"trace[333947239] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"940.489265ms","start":"2026-07-25T06:51:33.584688Z","end":"2026-07-25T06:51:34.525178Z","steps":["trace[333947239] 'process raft request' (duration: 939.670068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.525353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.881216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:51:34.525401Z","caller":"traceutil/trace.go:171","msg":"trace[662709229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"812.0438ms","start":"2026-07-25T06:51:33.713344Z","end":"2026-07-25T06:51:34.525388Z","steps":["trace[662709229] 'agreement among raft nodes before linearized reading' (duration: 811.930728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.525442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.713313Z","time spent":"812.120401ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T06:51:34.525535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.584656Z","time spent":"940.6997ms","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:51:34.525689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.938682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-25T06:51:34.52573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.042322ms","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-07-25T06:51:34.525789Z","caller":"traceutil/trace.go:171","msg":"trace[1851586929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"744.095245ms","start":"2026-07-25T06:51:33.781667Z","end":"2026-07-25T06:51:34.525762Z","steps":["trace[1851586929] 'agreement among raft nodes before linearized reading' (duration: 743.749058ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:51:34.525818Z","caller":"traceutil/trace.go:171","msg":"trace[56005150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"737.168275ms","start":"2026-07-25T06:51:33.788627Z","end":"2026-07-25T06:51:34.525795Z","steps":["trace[56005150] 'agreement among raft nodes before linearized reading' (duration: 736.93354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.525851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.781654Z","time spent":"744.186188ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T06:51:34.525867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.427503ms","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-07-25T06:51:34.52593Z","caller":"traceutil/trace.go:171","msg":"trace[2000556122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"561.531665ms","start":"2026-07-25T06:51:33.964374Z","end":"2026-07-25T06:51:34.525906Z","steps":["trace[2000556122] 'agreement among raft nodes before linearized reading' (duration: 561.410383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.52587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.788615Z","time spent":"737.246057ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T06:51:34.525978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.964346Z","time spent":"561.620908ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:51:34.525731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.009572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:51:34.526052Z","caller":"traceutil/trace.go:171","msg":"trace[1528480796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"737.36483ms","start":"2026-07-25T06:51:33.788666Z","end":"2026-07-25T06:51:34.526031Z","steps":["trace[1528480796] 'agreement among raft nodes before linearized reading' (duration: 736.777657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:34.526315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:33.788661Z","time spent":"737.620195ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T06:51:36.399052Z","caller":"traceutil/trace.go:171","msg":"trace[1297244308] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"112.608515ms","start":"2026-07-25T06:51:36.286409Z","end":"2026-07-25T06:51:36.399018Z","steps":["trace[1297244308] 'process raft request' (duration: 112.434191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.637769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.285699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T06:51:50.637823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.118773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:51:50.637942Z","caller":"traceutil/trace.go:171","msg":"trace[855387209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"925.284727ms","start":"2026-07-25T06:51:49.712624Z","end":"2026-07-25T06:51:50.637908Z","steps":["trace[855387209] 'range keys from in-memory index tree' (duration: 925.055902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.638004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:49.712606Z","time spent":"925.381428ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T06:51:50.638235Z","caller":"traceutil/trace.go:171","msg":"trace[45526703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"648.715158ms","start":"2026-07-25T06:51:49.989449Z","end":"2026-07-25T06:51:50.638164Z","steps":["trace[45526703] 'range keys from in-memory index tree' (duration: 648.030523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.638806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:49.989427Z","time spent":"649.36323ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T06:51:50.640764Z","caller":"traceutil/trace.go:171","msg":"trace[27206490] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"499.9139ms","start":"2026-07-25T06:51:50.14083Z","end":"2026-07-25T06:51:50.640744Z","steps":["trace[27206490] 'process raft request' (duration: 499.659365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.640926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:51:50.140787Z","time spent":"500.045973ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:51:50.777143Z","caller":"traceutil/trace.go:171","msg":"trace[1125098171] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"131.392165ms","start":"2026-07-25T06:51:50.64571Z","end":"2026-07-25T06:51:50.777102Z","steps":["trace[1125098171] 'process raft request' (duration: 119.987282ms)","trace[1125098171] 'compare' (duration: 11.212099ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:51:50.777407Z","caller":"traceutil/trace.go:171","msg":"trace[1320812564] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"123.232226ms","start":"2026-07-25T06:51:50.654134Z","end":"2026-07-25T06:51:50.777367Z","steps":["trace[1320812564] 'read index received' (duration: 111.712531ms)","trace[1320812564] 'applied index is now lower than readState.Index' (duration: 11.517675ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:51:50.777529Z","caller":"traceutil/trace.go:171","msg":"trace[1656793583] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"130.445946ms","start":"2026-07-25T06:51:50.647071Z","end":"2026-07-25T06:51:50.777517Z","steps":["trace[1656793583] 'process raft request' (duration: 129.985977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.777677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.518422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:51:50.77801Z","caller":"traceutil/trace.go:171","msg":"trace[476624228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"123.886878ms","start":"2026-07-25T06:51:50.654105Z","end":"2026-07-25T06:51:50.777992Z","steps":["trace[476624228] 'agreement among raft nodes before linearized reading' (duration: 123.436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.777787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.944785ms","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-07-25T06:51:50.778344Z","caller":"traceutil/trace.go:171","msg":"trace[59995907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"113.512176ms","start":"2026-07-25T06:51:50.664821Z","end":"2026-07-25T06:51:50.778333Z","steps":["trace[59995907] 'agreement among raft nodes before linearized reading' (duration: 112.896784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:51:50.777798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.602488ms","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-07-25T06:51:50.778816Z","caller":"traceutil/trace.go:171","msg":"trace[1820611597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"114.674428ms","start":"2026-07-25T06:51:50.664131Z","end":"2026-07-25T06:51:50.778806Z","steps":["trace[1820611597] 'agreement among raft nodes before linearized reading' (duration: 113.518686ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:52:04.524951Z","caller":"traceutil/trace.go:171","msg":"trace[1459665220] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"179.29215ms","start":"2026-07-25T06:52:04.345626Z","end":"2026-07-25T06:52:04.524918Z","steps":["trace[1459665220] 'process raft request' (duration: 179.047615ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:39.266195Z","caller":"traceutil/trace.go:171","msg":"trace[804962480] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"138.956458ms","start":"2026-07-25T06:53:39.127213Z","end":"2026-07-25T06:53:39.266169Z","steps":["trace[804962480] 'process raft request' (duration: 138.614133ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:39.266289Z","caller":"traceutil/trace.go:171","msg":"trace[1803556184] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"138.903137ms","start":"2026-07-25T06:53:39.127324Z","end":"2026-07-25T06:53:39.266227Z","steps":["trace[1803556184] 'process raft request' (duration: 138.690614ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:39.266414Z","caller":"traceutil/trace.go:171","msg":"trace[118002548] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1508; }","duration":"107.208822ms","start":"2026-07-25T06:53:39.159156Z","end":"2026-07-25T06:53:39.266365Z","steps":["trace[118002548] 'read index received' (duration: 106.960029ms)","trace[118002548] 'applied index is now lower than readState.Index' (duration: 246.543µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:53:39.266618Z","caller":"traceutil/trace.go:171","msg":"trace[697373863] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"127.343129ms","start":"2026-07-25T06:53:39.139225Z","end":"2026-07-25T06:53:39.266568Z","steps":["trace[697373863] 'process raft request' (duration: 126.847502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:39.266741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.278142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-7d6b59cfdc-8fmfx\" ","response":"range_response_count:1 size:3812"} {"level":"info","ts":"2026-07-25T06:53:39.266809Z","caller":"traceutil/trace.go:171","msg":"trace[1301025346] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-7d6b59cfdc-8fmfx; range_end:; response_count:1; response_revision:1452; }","duration":"139.368164ms","start":"2026-07-25T06:53:39.127401Z","end":"2026-07-25T06:53:39.266769Z","steps":["trace[1301025346] 'agreement among raft nodes before linearized reading' (duration: 139.170451ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:39.274505Z","caller":"traceutil/trace.go:171","msg":"trace[53962088] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"109.870608ms","start":"2026-07-25T06:53:39.164584Z","end":"2026-07-25T06:53:39.274455Z","steps":["trace[53962088] 'process raft request' (duration: 109.341791ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:40.244454Z","caller":"traceutil/trace.go:171","msg":"trace[960182097] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"100.060762ms","start":"2026-07-25T06:53:40.14435Z","end":"2026-07-25T06:53:40.244411Z","steps":["trace[960182097] 'process raft request' (duration: 98.154266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:43.930404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.20653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:53:43.930535Z","caller":"traceutil/trace.go:171","msg":"trace[505784087] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1527; }","duration":"221.382033ms","start":"2026-07-25T06:53:43.709121Z","end":"2026-07-25T06:53:43.930503Z","steps":["trace[505784087] 'range keys from in-memory index tree' (duration: 221.103589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:43.930534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.722767ms","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:5"} {"level":"info","ts":"2026-07-25T06:53:43.930605Z","caller":"traceutil/trace.go:171","msg":"trace[437521987] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1527; }","duration":"199.823778ms","start":"2026-07-25T06:53:43.730761Z","end":"2026-07-25T06:53:43.930585Z","steps":["trace[437521987] 'count revisions from in-memory index tree' (duration: 199.656376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:43.930632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.506696ms","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-07-25T06:53:43.930728Z","caller":"traceutil/trace.go:171","msg":"trace[1901544795] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1527; }","duration":"224.655448ms","start":"2026-07-25T06:53:43.70605Z","end":"2026-07-25T06:53:43.930706Z","steps":["trace[1901544795] 'count revisions from in-memory index tree' (duration: 224.418994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:43.930875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.89623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:53:43.930878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.680228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:53:43.930913Z","caller":"traceutil/trace.go:171","msg":"trace[1439332554] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1527; }","duration":"200.002521ms","start":"2026-07-25T06:53:43.7309Z","end":"2026-07-25T06:53:43.930902Z","steps":["trace[1439332554] 'range keys from in-memory index tree' (duration: 199.731918ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:43.930927Z","caller":"traceutil/trace.go:171","msg":"trace[1872044353] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1527; }","duration":"224.739428ms","start":"2026-07-25T06:53:43.706169Z","end":"2026-07-25T06:53:43.930909Z","steps":["trace[1872044353] 'range keys from in-memory index tree' (duration: 224.647058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:43.931055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.468553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:53:43.931104Z","caller":"traceutil/trace.go:171","msg":"trace[1079479150] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1527; }","duration":"199.537515ms","start":"2026-07-25T06:53:43.731555Z","end":"2026-07-25T06:53:43.931092Z","steps":["trace[1079479150] 'range keys from in-memory index tree' (duration: 199.410213ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:47.594172Z","caller":"traceutil/trace.go:171","msg":"trace[1165866863] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"164.544905ms","start":"2026-07-25T06:53:47.429579Z","end":"2026-07-25T06:53:47.594124Z","steps":["trace[1165866863] 'process raft request' (duration: 164.286311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:47.9089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.709204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:53:47.909149Z","caller":"traceutil/trace.go:171","msg":"trace[1987279678] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1710; }","duration":"478.539057ms","start":"2026-07-25T06:53:47.430584Z","end":"2026-07-25T06:53:47.909123Z","steps":["trace[1987279678] 'read index received' (duration: 163.388769ms)","trace[1987279678] 'applied index is now lower than readState.Index' (duration: 315.148848ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:53:47.909299Z","caller":"traceutil/trace.go:171","msg":"trace[1259658705] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"474.155308ms","start":"2026-07-25T06:53:47.435123Z","end":"2026-07-25T06:53:47.909278Z","steps":["trace[1259658705] 'process raft request' (duration: 473.934815ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:47.909269Z","caller":"traceutil/trace.go:171","msg":"trace[1324497240] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"478.997253ms","start":"2026-07-25T06:53:47.430141Z","end":"2026-07-25T06:53:47.909139Z","steps":["trace[1324497240] 'process raft request' (duration: 227.909084ms)","trace[1324497240] 'compare' (duration: 250.503101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:53:47.909496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:47.43511Z","time spent":"474.33588ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:53:47.909658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.942563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T06:53:47.909712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.101375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T06:53:47.909723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:47.430111Z","time spent":"479.378169ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:53:47.909764Z","caller":"traceutil/trace.go:171","msg":"trace[2145318172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1653; }","duration":"479.097305ms","start":"2026-07-25T06:53:47.430639Z","end":"2026-07-25T06:53:47.909736Z","steps":["trace[2145318172] 'agreement among raft nodes before linearized reading' (duration: 478.758061ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:47.909777Z","caller":"traceutil/trace.go:171","msg":"trace[1666387481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1653; }","duration":"479.201367ms","start":"2026-07-25T06:53:47.430558Z","end":"2026-07-25T06:53:47.909759Z","steps":["trace[1666387481] 'agreement among raft nodes before linearized reading' (duration: 478.894283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:47.909821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:47.430546Z","time spent":"479.266367ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T06:53:47.909819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:47.430625Z","time spent":"479.185276ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:53:47.909802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.515304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-mqxsl\" ","response":"range_response_count:1 size:4179"} {"level":"info","ts":"2026-07-25T06:53:47.909892Z","caller":"traceutil/trace.go:171","msg":"trace[1348567389] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-mqxsl; range_end:; response_count:1; response_revision:1653; }","duration":"276.719997ms","start":"2026-07-25T06:53:47.633145Z","end":"2026-07-25T06:53:47.909865Z","steps":["trace[1348567389] 'agreement among raft nodes before linearized reading' (duration: 276.482084ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:50.067622Z","caller":"traceutil/trace.go:171","msg":"trace[786705026] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"147.465656ms","start":"2026-07-25T06:53:49.920121Z","end":"2026-07-25T06:53:50.067587Z","steps":["trace[786705026] 'process raft request' (duration: 147.262073ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:50.124276Z","caller":"traceutil/trace.go:171","msg":"trace[1928222552] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1723; }","duration":"186.840249ms","start":"2026-07-25T06:53:49.937407Z","end":"2026-07-25T06:53:50.124247Z","steps":["trace[1928222552] 'read index received' (duration: 130.069851ms)","trace[1928222552] 'applied index is now lower than readState.Index' (duration: 56.769048ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:53:50.124381Z","caller":"traceutil/trace.go:171","msg":"trace[115361118] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"203.909ms","start":"2026-07-25T06:53:49.920447Z","end":"2026-07-25T06:53:50.124356Z","steps":["trace[115361118] 'process raft request' (duration: 203.642496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:50.124545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.112742ms","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-07-25T06:53:50.124658Z","caller":"traceutil/trace.go:171","msg":"trace[1777349589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1665; }","duration":"187.273625ms","start":"2026-07-25T06:53:49.93737Z","end":"2026-07-25T06:53:50.124644Z","steps":["trace[1777349589] 'agreement among raft nodes before linearized reading' (duration: 187.025402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:50.124797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.384184ms","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-07-25T06:53:50.12495Z","caller":"traceutil/trace.go:171","msg":"trace[941085021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1665; }","duration":"178.592968ms","start":"2026-07-25T06:53:49.946322Z","end":"2026-07-25T06:53:50.124915Z","steps":["trace[941085021] 'agreement among raft nodes before linearized reading' (duration: 178.173392ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:50.334765Z","caller":"traceutil/trace.go:171","msg":"trace[1622509955] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"201.892321ms","start":"2026-07-25T06:53:50.132823Z","end":"2026-07-25T06:53:50.334715Z","steps":["trace[1622509955] 'process raft request' (duration: 194.492961ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:52.101989Z","caller":"traceutil/trace.go:171","msg":"trace[640623619] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"249.114885ms","start":"2026-07-25T06:53:51.852848Z","end":"2026-07-25T06:53:52.101963Z","steps":["trace[640623619] 'process raft request' (duration: 248.948482ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:52.107454Z","caller":"traceutil/trace.go:171","msg":"trace[541205153] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"233.443093ms","start":"2026-07-25T06:53:51.873969Z","end":"2026-07-25T06:53:52.107412Z","steps":["trace[541205153] 'process raft request' (duration: 233.244621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:56.581384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.868743ms","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-07-25T06:53:56.581617Z","caller":"traceutil/trace.go:171","msg":"trace[650019146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1721; }","duration":"223.183037ms","start":"2026-07-25T06:53:56.3584Z","end":"2026-07-25T06:53:56.581583Z","steps":["trace[650019146] 'range keys from in-memory index tree' (duration: 222.64515ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:56.582348Z","caller":"traceutil/trace.go:171","msg":"trace[18422002] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"207.425426ms","start":"2026-07-25T06:53:56.374888Z","end":"2026-07-25T06:53:56.582313Z","steps":["trace[18422002] 'process raft request' (duration: 161.032603ms)","trace[18422002] 'compare' (duration: 46.15206ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:53:56.749122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.32555ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-25T06:53:56.890651Z","caller":"traceutil/trace.go:171","msg":"trace[1305503333] linearizableReadLoop","detail":"{readStateIndex:1786; appliedIndex:1785; }","duration":"191.673484ms","start":"2026-07-25T06:53:56.698943Z","end":"2026-07-25T06:53:56.890617Z","steps":["trace[1305503333] 'read index received' (duration: 191.37693ms)","trace[1305503333] 'applied index is now lower than readState.Index' (duration: 295.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:53:56.890897Z","caller":"traceutil/trace.go:171","msg":"trace[1749132423] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"303.632779ms","start":"2026-07-25T06:53:56.587247Z","end":"2026-07-25T06:53:56.890879Z","steps":["trace[1749132423] 'process raft request' (duration: 303.102492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:56.891121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.587208Z","time spent":"303.72353ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:53:56.891193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.208542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-tz8bh\" ","response":"range_response_count:1 size:4218"} {"level":"info","ts":"2026-07-25T06:53:56.891309Z","caller":"traceutil/trace.go:171","msg":"trace[1937296692] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-tz8bh; range_end:; response_count:1; response_revision:1723; }","duration":"192.390544ms","start":"2026-07-25T06:53:56.698883Z","end":"2026-07-25T06:53:56.891273Z","steps":["trace[1937296692] 'agreement among raft nodes before linearized reading' (duration: 192.10032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.400912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.016948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:53:57.401047Z","caller":"traceutil/trace.go:171","msg":"trace[1138819195] linearizableReadLoop","detail":"{readStateIndex:1787; appliedIndex:1786; }","duration":"510.189187ms","start":"2026-07-25T06:53:56.890836Z","end":"2026-07-25T06:53:57.401025Z","steps":["trace[1138819195] 'read index received' (duration: 379.014827ms)","trace[1138819195] 'applied index is now lower than readState.Index' (duration: 131.17313ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:53:57.401126Z","caller":"traceutil/trace.go:171","msg":"trace[1966988857] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"582.939704ms","start":"2026-07-25T06:53:56.818113Z","end":"2026-07-25T06:53:57.401053Z","steps":["trace[1966988857] 'process raft request' (duration: 451.680883ms)","trace[1966988857] 'compare' (duration: 130.866826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:53:57.401197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.560523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:53:57.401189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.148864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:53:57.401246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.818086Z","time spent":"583.103136ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:53:57.401288Z","caller":"traceutil/trace.go:171","msg":"trace[666260998] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1724; }","duration":"559.304006ms","start":"2026-07-25T06:53:56.841964Z","end":"2026-07-25T06:53:57.401267Z","steps":["trace[666260998] 'agreement among raft nodes before linearized reading' (duration: 559.147434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.401335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.841934Z","time spent":"559.388128ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"info","ts":"2026-07-25T06:53:57.401979Z","caller":"traceutil/trace.go:171","msg":"trace[664033081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1724; }","duration":"688.621454ms","start":"2026-07-25T06:53:56.712594Z","end":"2026-07-25T06:53:57.401216Z","steps":["trace[664033081] 'agreement among raft nodes before linearized reading' (duration: 688.546773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.402145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.712583Z","time spent":"689.521986ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T06:53:57.880094Z","caller":"traceutil/trace.go:171","msg":"trace[914193911] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"797.968486ms","start":"2026-07-25T06:53:57.082095Z","end":"2026-07-25T06:53:57.880064Z","steps":["trace[914193911] 'process raft request' (duration: 797.887115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.880483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.082075Z","time spent":"798.31817ms","remote":"127.0.0.1:44114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:53:57.880147Z","caller":"traceutil/trace.go:171","msg":"trace[1234134149] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"979.985979ms","start":"2026-07-25T06:53:56.900112Z","end":"2026-07-25T06:53:57.880098Z","steps":["trace[1234134149] 'process raft request' (duration: 960.193154ms)","trace[1234134149] 'compare' (duration: 19.540122ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:53:57.880927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.90008Z","time spent":"980.670359ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:53:57.934983Z","caller":"traceutil/trace.go:171","msg":"trace[699856438] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"656.069742ms","start":"2026-07-25T06:53:57.278866Z","end":"2026-07-25T06:53:57.934935Z","steps":["trace[699856438] 'process raft request' (duration: 655.847099ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:57.935046Z","caller":"traceutil/trace.go:171","msg":"trace[204832790] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"528.496461ms","start":"2026-07-25T06:53:57.406533Z","end":"2026-07-25T06:53:57.935029Z","steps":["trace[204832790] 'process raft request' (duration: 528.339349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.93523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.278836Z","time spent":"656.266435ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:53:57.935312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.274399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-25T06:53:57.935369Z","caller":"traceutil/trace.go:171","msg":"trace[328428539] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1728; }","duration":"745.35749ms","start":"2026-07-25T06:53:57.189998Z","end":"2026-07-25T06:53:57.935355Z","steps":["trace[328428539] 'agreement among raft nodes before linearized reading' (duration: 745.066926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.935344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.406503Z","time spent":"528.619013ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:53:57.935412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.189916Z","time spent":"745.487652ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":192,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-25T06:53:57.935594Z","caller":"traceutil/trace.go:171","msg":"trace[1597204384] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1787; }","duration":"533.864943ms","start":"2026-07-25T06:53:57.401108Z","end":"2026-07-25T06:53:57.934973Z","steps":["trace[1597204384] 'read index received' (duration: 459.233751ms)","trace[1597204384] 'applied index is now lower than readState.Index' (duration: 74.629982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:53:57.93578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.836211ms","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-07-25T06:53:57.93583Z","caller":"traceutil/trace.go:171","msg":"trace[2135912015] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1728; }","duration":"980.920612ms","start":"2026-07-25T06:53:56.954895Z","end":"2026-07-25T06:53:57.935816Z","steps":["trace[2135912015] 'agreement among raft nodes before linearized reading' (duration: 980.808751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.935862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:56.954852Z","time spent":"981.003073ms","remote":"127.0.0.1:44262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":21,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-25T06:53:57.949462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.088187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:53:57.949514Z","caller":"traceutil/trace.go:171","msg":"trace[1714105029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1730; }","duration":"546.184118ms","start":"2026-07-25T06:53:57.403316Z","end":"2026-07-25T06:53:57.9495Z","steps":["trace[1714105029] 'agreement among raft nodes before linearized reading' (duration: 546.105207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.949544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.403295Z","time spent":"546.242148ms","remote":"127.0.0.1:58100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T06:53:57.949583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.946775ms","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-07-25T06:53:57.949646Z","caller":"traceutil/trace.go:171","msg":"trace[1355419973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1730; }","duration":"300.073785ms","start":"2026-07-25T06:53:57.649555Z","end":"2026-07-25T06:53:57.949629Z","steps":["trace[1355419973] 'agreement among raft nodes before linearized reading' (duration: 299.839012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:53:57.94968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:53:57.649524Z","time spent":"300.150067ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T06:53:59.910266Z","caller":"traceutil/trace.go:171","msg":"trace[1387004688] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"137.977737ms","start":"2026-07-25T06:53:59.772257Z","end":"2026-07-25T06:53:59.910235Z","steps":["trace[1387004688] 'process raft request' (duration: 137.894676ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:53:59.910381Z","caller":"traceutil/trace.go:171","msg":"trace[2108514378] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"145.977364ms","start":"2026-07-25T06:53:59.764368Z","end":"2026-07-25T06:53:59.910346Z","steps":["trace[2108514378] 'process raft request' (duration: 86.408887ms)","trace[2108514378] 'compare' (duration: 59.156711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:12.401467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.885175ms","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-07-25T06:54:12.401564Z","caller":"traceutil/trace.go:171","msg":"trace[386931109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2037; }","duration":"131.04249ms","start":"2026-07-25T06:54:12.270501Z","end":"2026-07-25T06:54:12.401543Z","steps":["trace[386931109] 'range keys from in-memory index tree' (duration: 130.563683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:12.849931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.55091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:54:12.850019Z","caller":"traceutil/trace.go:171","msg":"trace[156184805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2040; }","duration":"137.711405ms","start":"2026-07-25T06:54:12.71229Z","end":"2026-07-25T06:54:12.850001Z","steps":["trace[156184805] 'range keys from in-memory index tree' (duration: 137.465246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:22.594557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578888027475685448,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T06:54:22.845298Z","caller":"traceutil/trace.go:171","msg":"trace[2058939777] linearizableReadLoop","detail":"{readStateIndex:2373; appliedIndex:2372; }","duration":"751.768937ms","start":"2026-07-25T06:54:22.093504Z","end":"2026-07-25T06:54:22.845272Z","steps":["trace[2058939777] 'read index received' (duration: 751.695265ms)","trace[2058939777] 'applied index is now lower than readState.Index' (duration: 72.652µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:22.845541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.006926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2406999c-4bd9-4db5-837c-046943fd7228\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-07-25T06:54:22.845644Z","caller":"traceutil/trace.go:171","msg":"trace[1306341510] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2406999c-4bd9-4db5-837c-046943fd7228; range_end:; response_count:1; response_revision:2274; }","duration":"752.181323ms","start":"2026-07-25T06:54:22.093441Z","end":"2026-07-25T06:54:22.845623Z","steps":["trace[1306341510] 'agreement among raft nodes before linearized reading' (duration: 751.971925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:22.845696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.093418Z","time spent":"752.267845ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3771,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2406999c-4bd9-4db5-837c-046943fd7228\" "} {"level":"info","ts":"2026-07-25T06:54:22.84588Z","caller":"traceutil/trace.go:171","msg":"trace[424430878] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"968.565004ms","start":"2026-07-25T06:54:21.876799Z","end":"2026-07-25T06:54:22.845364Z","steps":["trace[424430878] 'process raft request' (duration: 968.267534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:22.846024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:21.876021Z","time spent":"969.913211ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:23.340945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.681017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:54:23.341278Z","caller":"traceutil/trace.go:171","msg":"trace[1841105207] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"660.71506ms","start":"2026-07-25T06:54:22.680523Z","end":"2026-07-25T06:54:23.341238Z","steps":["trace[1841105207] 'process raft request' (duration: 660.558845ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:23.341275Z","caller":"traceutil/trace.go:171","msg":"trace[1458272939] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"718.184527ms","start":"2026-07-25T06:54:22.623023Z","end":"2026-07-25T06:54:23.341208Z","steps":["trace[1458272939] 'process raft request' (duration: 320.896129ms)","trace[1458272939] 'compare' (duration: 396.535842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:23.341405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.680495Z","time spent":"660.849255ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:23.341449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.496968ms","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-07-25T06:54:23.341458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.868988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T06:54:23.341446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.795871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:54:23.34151Z","caller":"traceutil/trace.go:171","msg":"trace[858579008] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2277; }","duration":"539.584681ms","start":"2026-07-25T06:54:22.8019Z","end":"2026-07-25T06:54:23.341485Z","steps":["trace[858579008] 'agreement among raft nodes before linearized reading' (duration: 539.364973ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:23.34152Z","caller":"traceutil/trace.go:171","msg":"trace[1910686448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2277; }","duration":"859.94662ms","start":"2026-07-25T06:54:22.481556Z","end":"2026-07-25T06:54:23.341503Z","steps":["trace[1910686448] 'agreement among raft nodes before linearized reading' (duration: 859.747773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.341518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.622995Z","time spent":"718.398085ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:23.341467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.883523ms","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-07-25T06:54:23.341568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.481549Z","time spent":"860.007013ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T06:54:23.341587Z","caller":"traceutil/trace.go:171","msg":"trace[653144064] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"559.668865ms","start":"2026-07-25T06:54:22.781908Z","end":"2026-07-25T06:54:23.341577Z","steps":["trace[653144064] 'process raft request' (duration: 559.253351ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:23.341587Z","caller":"traceutil/trace.go:171","msg":"trace[568909428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2277; }","duration":"851.025598ms","start":"2026-07-25T06:54:22.490545Z","end":"2026-07-25T06:54:23.341571Z","steps":["trace[568909428] 'agreement among raft nodes before linearized reading' (duration: 850.712017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.341631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.490533Z","time spent":"851.083999ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-25T06:54:23.341537Z","caller":"traceutil/trace.go:171","msg":"trace[544278921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2277; }","duration":"628.937676ms","start":"2026-07-25T06:54:22.712578Z","end":"2026-07-25T06:54:23.341516Z","steps":["trace[544278921] 'agreement among raft nodes before linearized reading' (duration: 628.7648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.341627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.514115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5\" ","response":"range_response_count:1 size:3617"} {"level":"warn","ts":"2026-07-25T06:54:23.341548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.801886Z","time spent":"539.653863ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T06:54:23.341688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.712564Z","time spent":"629.112022ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T06:54:23.341698Z","caller":"traceutil/trace.go:171","msg":"trace[1417733423] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5; range_end:; response_count:1; response_revision:2277; }","duration":"853.605149ms","start":"2026-07-25T06:54:22.488075Z","end":"2026-07-25T06:54:23.34168Z","steps":["trace[1417733423] 'agreement among raft nodes before linearized reading' (duration: 853.437153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.34176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.488064Z","time spent":"853.683031ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3641,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5\" "} {"level":"warn","ts":"2026-07-25T06:54:23.34182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.189795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T06:54:23.341868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035260293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:54:23.34187Z","caller":"traceutil/trace.go:171","msg":"trace[1536463558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2277; }","duration":"867.266218ms","start":"2026-07-25T06:54:22.474589Z","end":"2026-07-25T06:54:23.341856Z","steps":["trace[1536463558] 'agreement among raft nodes before linearized reading' (duration: 867.133903ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:23.341926Z","caller":"traceutil/trace.go:171","msg":"trace[694282409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2277; }","duration":"1.035355706s","start":"2026-07-25T06:54:22.306557Z","end":"2026-07-25T06:54:23.341912Z","steps":["trace[694282409] 'agreement among raft nodes before linearized reading' (duration: 1.035209431s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.341933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.47457Z","time spent":"867.35173ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T06:54:23.341968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.306527Z","time spent":"1.035431008s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T06:54:23.341645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.781884Z","time spent":"559.723297ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:23.341246Z","caller":"traceutil/trace.go:171","msg":"trace[1069506377] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2373; }","duration":"495.842856ms","start":"2026-07-25T06:54:22.845377Z","end":"2026-07-25T06:54:23.341219Z","steps":["trace[1069506377] 'read index received' (duration: 98.542017ms)","trace[1069506377] 'applied index is now lower than readState.Index' (duration: 397.299759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:23.342242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212088577s","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-07-25T06:54:23.342291Z","caller":"traceutil/trace.go:171","msg":"trace[169785388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2277; }","duration":"1.212159938s","start":"2026-07-25T06:54:22.130117Z","end":"2026-07-25T06:54:23.342277Z","steps":["trace[169785388] 'agreement among raft nodes before linearized reading' (duration: 1.212021404s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.34233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.130106Z","time spent":"1.212214181s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T06:54:23.342583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.348431ms","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-07-25T06:54:23.342647Z","caller":"traceutil/trace.go:171","msg":"trace[1262498414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2278; }","duration":"308.453965ms","start":"2026-07-25T06:54:23.034178Z","end":"2026-07-25T06:54:23.342632Z","steps":["trace[1262498414] 'agreement among raft nodes before linearized reading' (duration: 308.292479ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:23.342643Z","caller":"traceutil/trace.go:171","msg":"trace[1435940588] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"390.713727ms","start":"2026-07-25T06:54:22.951912Z","end":"2026-07-25T06:54:23.342626Z","steps":["trace[1435940588] 'process raft request' (duration: 390.412116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:23.342694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:23.034154Z","time spent":"308.529748ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:54:23.342741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:22.951886Z","time spent":"390.80558ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:28.454273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.14869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:54:28.455046Z","caller":"traceutil/trace.go:171","msg":"trace[97072261] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"468.11417ms","start":"2026-07-25T06:54:27.986903Z","end":"2026-07-25T06:54:28.455017Z","steps":["trace[97072261] 'process raft request' (duration: 334.119011ms)","trace[97072261] 'compare' (duration: 132.434505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:28.455188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:27.986876Z","time spent":"468.246364ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:28.458735Z","caller":"traceutil/trace.go:171","msg":"trace[527812981] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"273.62114ms","start":"2026-07-25T06:54:28.185069Z","end":"2026-07-25T06:54:28.45869Z","steps":["trace[527812981] 'process raft request' (duration: 273.419783ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:28.458959Z","caller":"traceutil/trace.go:171","msg":"trace[1127943553] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"216.121436ms","start":"2026-07-25T06:54:28.242811Z","end":"2026-07-25T06:54:28.458933Z","steps":["trace[1127943553] 'process raft request' (duration: 215.790314ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.578795Z","caller":"traceutil/trace.go:171","msg":"trace[1638101028] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"193.426969ms","start":"2026-07-25T06:54:33.38533Z","end":"2026-07-25T06:54:33.578757Z","steps":["trace[1638101028] 'process raft request' (duration: 193.208742ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.597193Z","caller":"traceutil/trace.go:171","msg":"trace[1836487081] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"172.750637ms","start":"2026-07-25T06:54:33.424411Z","end":"2026-07-25T06:54:33.597162Z","steps":["trace[1836487081] 'process raft request' (duration: 172.645964ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.597448Z","caller":"traceutil/trace.go:171","msg":"trace[1134306517] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"172.395615ms","start":"2026-07-25T06:54:33.424944Z","end":"2026-07-25T06:54:33.597339Z","steps":["trace[1134306517] 'process raft request' (duration: 172.160127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:33.597531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.074771ms","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-07-25T06:54:33.59725Z","caller":"traceutil/trace.go:171","msg":"trace[1664950380] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"197.523706ms","start":"2026-07-25T06:54:33.399675Z","end":"2026-07-25T06:54:33.597198Z","steps":["trace[1664950380] 'process raft request' (duration: 197.244656ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.597593Z","caller":"traceutil/trace.go:171","msg":"trace[1305050093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2387; }","duration":"168.192104ms","start":"2026-07-25T06:54:33.429386Z","end":"2026-07-25T06:54:33.597578Z","steps":["trace[1305050093] 'agreement among raft nodes before linearized reading' (duration: 167.982347ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.597327Z","caller":"traceutil/trace.go:171","msg":"trace[1723707268] linearizableReadLoop","detail":"{readStateIndex:2489; appliedIndex:2485; }","duration":"167.869574ms","start":"2026-07-25T06:54:33.429433Z","end":"2026-07-25T06:54:33.597302Z","steps":["trace[1723707268] 'read index received' (duration: 149.075776ms)","trace[1723707268] 'applied index is now lower than readState.Index' (duration: 18.792088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:33.597893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.053875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T06:54:33.597899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.645816ms","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-07-25T06:54:33.597975Z","caller":"traceutil/trace.go:171","msg":"trace[1784053788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2387; }","duration":"158.75949ms","start":"2026-07-25T06:54:33.439194Z","end":"2026-07-25T06:54:33.597953Z","steps":["trace[1784053788] 'agreement among raft nodes before linearized reading' (duration: 158.567643ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:33.597945Z","caller":"traceutil/trace.go:171","msg":"trace[54683539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2387; }","duration":"158.140439ms","start":"2026-07-25T06:54:33.43979Z","end":"2026-07-25T06:54:33.597931Z","steps":["trace[54683539] 'agreement among raft nodes before linearized reading' (duration: 158.011405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:33.598152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.395967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:54:33.598299Z","caller":"traceutil/trace.go:171","msg":"trace[1936399045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2387; }","duration":"158.589674ms","start":"2026-07-25T06:54:33.439694Z","end":"2026-07-25T06:54:33.598283Z","steps":["trace[1936399045] 'agreement among raft nodes before linearized reading' (duration: 158.116898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:33.598674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.409591ms","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-07-25T06:54:33.598747Z","caller":"traceutil/trace.go:171","msg":"trace[1981009926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2387; }","duration":"159.546096ms","start":"2026-07-25T06:54:33.439185Z","end":"2026-07-25T06:54:33.598731Z","steps":["trace[1981009926] 'agreement among raft nodes before linearized reading' (duration: 158.646456ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:35.52855Z","caller":"traceutil/trace.go:171","msg":"trace[356685267] linearizableReadLoop","detail":"{readStateIndex:2495; appliedIndex:2494; }","duration":"102.389885ms","start":"2026-07-25T06:54:35.426134Z","end":"2026-07-25T06:54:35.528524Z","steps":["trace[356685267] 'read index received' (duration: 102.196939ms)","trace[356685267] 'applied index is now lower than readState.Index' (duration: 192.136µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:54:35.528674Z","caller":"traceutil/trace.go:171","msg":"trace[1343627589] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"372.829453ms","start":"2026-07-25T06:54:35.155809Z","end":"2026-07-25T06:54:35.528638Z","steps":["trace[1343627589] 'process raft request' (duration: 372.569164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.52872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.560081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:54:35.528757Z","caller":"traceutil/trace.go:171","msg":"trace[1967902026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2393; }","duration":"102.665935ms","start":"2026-07-25T06:54:35.42608Z","end":"2026-07-25T06:54:35.528746Z","steps":["trace[1967902026] 'agreement among raft nodes before linearized reading' (duration: 102.53967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.528844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.15578Z","time spent":"372.965048ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:35.752524Z","caller":"traceutil/trace.go:171","msg":"trace[1581494312] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"167.65613ms","start":"2026-07-25T06:54:35.584839Z","end":"2026-07-25T06:54:35.752495Z","steps":["trace[1581494312] 'process raft request' (duration: 167.482935ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:35.776358Z","caller":"traceutil/trace.go:171","msg":"trace[2106590727] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"172.775109ms","start":"2026-07-25T06:54:35.603536Z","end":"2026-07-25T06:54:35.776311Z","steps":["trace[2106590727] 'process raft request' (duration: 172.406687ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:35.776704Z","caller":"traceutil/trace.go:171","msg":"trace[1718160157] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"173.09325ms","start":"2026-07-25T06:54:35.603585Z","end":"2026-07-25T06:54:35.776678Z","steps":["trace[1718160157] 'process raft request' (duration: 172.648575ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:35.776821Z","caller":"traceutil/trace.go:171","msg":"trace[1706481650] linearizableReadLoop","detail":"{readStateIndex:2499; appliedIndex:2495; }","duration":"167.558796ms","start":"2026-07-25T06:54:35.609246Z","end":"2026-07-25T06:54:35.776805Z","steps":["trace[1706481650] 'read index received' (duration: 143.143177ms)","trace[1706481650] 'applied index is now lower than readState.Index' (duration: 24.414099ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:54:35.776898Z","caller":"traceutil/trace.go:171","msg":"trace[835701811] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"173.037258ms","start":"2026-07-25T06:54:35.603844Z","end":"2026-07-25T06:54:35.776882Z","steps":["trace[835701811] 'process raft request' (duration: 172.736218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.777124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.859756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:54:35.777158Z","caller":"traceutil/trace.go:171","msg":"trace[1164944035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2397; }","duration":"167.909708ms","start":"2026-07-25T06:54:35.60924Z","end":"2026-07-25T06:54:35.77715Z","steps":["trace[1164944035] 'agreement among raft nodes before linearized reading' (duration: 167.797364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.777197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.260727ms","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-07-25T06:54:35.777234Z","caller":"traceutil/trace.go:171","msg":"trace[1925599496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2397; }","duration":"167.309748ms","start":"2026-07-25T06:54:35.609913Z","end":"2026-07-25T06:54:35.777223Z","steps":["trace[1925599496] 'agreement among raft nodes before linearized reading' (duration: 167.232366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.777124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.484664ms","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-07-25T06:54:35.777306Z","caller":"traceutil/trace.go:171","msg":"trace[1163677014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2397; }","duration":"167.721262ms","start":"2026-07-25T06:54:35.609577Z","end":"2026-07-25T06:54:35.777299Z","steps":["trace[1163677014] 'agreement among raft nodes before linearized reading' (duration: 167.436432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.777328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.680527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T06:54:35.777372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.483571ms","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-07-25T06:54:35.777405Z","caller":"traceutil/trace.go:171","msg":"trace[1239010617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2397; }","duration":"166.528392ms","start":"2026-07-25T06:54:35.610868Z","end":"2026-07-25T06:54:35.777397Z","steps":["trace[1239010617] 'agreement among raft nodes before linearized reading' (duration: 166.448069ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:35.777413Z","caller":"traceutil/trace.go:171","msg":"trace[2084843989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2397; }","duration":"166.799622ms","start":"2026-07-25T06:54:35.610594Z","end":"2026-07-25T06:54:35.777394Z","steps":["trace[2084843989] 'agreement among raft nodes before linearized reading' (duration: 166.541873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:35.777656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.234632ms","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-07-25T06:54:35.777689Z","caller":"traceutil/trace.go:171","msg":"trace[967726481] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2397; }","duration":"166.276614ms","start":"2026-07-25T06:54:35.611402Z","end":"2026-07-25T06:54:35.777679Z","steps":["trace[967726481] 'agreement among raft nodes before linearized reading' (duration: 165.614952ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:36.217786Z","caller":"traceutil/trace.go:171","msg":"trace[1206735907] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"436.165703ms","start":"2026-07-25T06:54:35.781588Z","end":"2026-07-25T06:54:36.217754Z","steps":["trace[1206735907] 'process raft request' (duration: 435.973397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:36.218214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.781568Z","time spent":"436.402701ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:36.350008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.337151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:54:36.3503Z","caller":"traceutil/trace.go:171","msg":"trace[768162583] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"567.82853ms","start":"2026-07-25T06:54:35.782428Z","end":"2026-07-25T06:54:36.350256Z","steps":["trace[768162583] 'process raft request' (duration: 451.136767ms)","trace[768162583] 'compare' (duration: 116.152385ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:54:36.350502Z","caller":"traceutil/trace.go:171","msg":"trace[2131477758] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"568.010806ms","start":"2026-07-25T06:54:35.782462Z","end":"2026-07-25T06:54:36.350472Z","steps":["trace[2131477758] 'process raft request' (duration: 567.655075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:36.350509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.782413Z","time spent":"567.973846ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:54:36.350604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.782421Z","time spent":"568.133001ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:36.350638Z","caller":"traceutil/trace.go:171","msg":"trace[1624772506] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"567.644254ms","start":"2026-07-25T06:54:35.782941Z","end":"2026-07-25T06:54:36.350585Z","steps":["trace[1624772506] 'process raft request' (duration: 567.341614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:36.350798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.782931Z","time spent":"567.823091ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:36.351192Z","caller":"traceutil/trace.go:171","msg":"trace[1742186294] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"388.773163ms","start":"2026-07-25T06:54:35.962405Z","end":"2026-07-25T06:54:36.351178Z","steps":["trace[1742186294] 'process raft request' (duration: 388.651559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:36.351273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.962369Z","time spent":"388.867326ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:36.35182Z","caller":"traceutil/trace.go:171","msg":"trace[901865697] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"567.778869ms","start":"2026-07-25T06:54:35.783444Z","end":"2026-07-25T06:54:36.351223Z","steps":["trace[901865697] 'process raft request' (duration: 567.090196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:36.352539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:35.783437Z","time spent":"568.786212ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:36.591612Z","caller":"traceutil/trace.go:171","msg":"trace[2141926960] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"164.766881ms","start":"2026-07-25T06:54:36.426812Z","end":"2026-07-25T06:54:36.591579Z","steps":["trace[2141926960] 'process raft request' (duration: 72.137982ms)","trace[2141926960] 'compare' (duration: 91.843493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:36.717402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.415434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-25T06:54:38.892263Z","caller":"traceutil/trace.go:171","msg":"trace[725296829] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"134.606097ms","start":"2026-07-25T06:54:38.75762Z","end":"2026-07-25T06:54:38.892226Z","steps":["trace[725296829] 'process raft request' (duration: 134.248465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:42.537826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.633247ms","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-07-25T06:54:42.537932Z","caller":"traceutil/trace.go:171","msg":"trace[1820290748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2452; }","duration":"139.827222ms","start":"2026-07-25T06:54:42.398083Z","end":"2026-07-25T06:54:42.53791Z","steps":["trace[1820290748] 'range keys from in-memory index tree' (duration: 139.403169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:42.53809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.679298ms","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-07-25T06:54:42.538172Z","caller":"traceutil/trace.go:171","msg":"trace[2036229856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2452; }","duration":"162.811712ms","start":"2026-07-25T06:54:42.375339Z","end":"2026-07-25T06:54:42.538151Z","steps":["trace[2036229856] 'range keys from in-memory index tree' (duration: 162.509763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:42.538141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.978564ms","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-07-25T06:54:42.538328Z","caller":"traceutil/trace.go:171","msg":"trace[530005903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2452; }","duration":"161.238412ms","start":"2026-07-25T06:54:42.37707Z","end":"2026-07-25T06:54:42.538309Z","steps":["trace[530005903] 'range keys from in-memory index tree' (duration: 160.745666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:42.538453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.934778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:54:42.538532Z","caller":"traceutil/trace.go:171","msg":"trace[992741754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2452; }","duration":"159.082873ms","start":"2026-07-25T06:54:42.379431Z","end":"2026-07-25T06:54:42.538514Z","steps":["trace[992741754] 'range keys from in-memory index tree' (duration: 158.741811ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:43.971785Z","caller":"traceutil/trace.go:171","msg":"trace[1391297369] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"382.790206ms","start":"2026-07-25T06:54:43.58897Z","end":"2026-07-25T06:54:43.97176Z","steps":["trace[1391297369] 'process raft request' (duration: 382.633671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:43.971978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:54:43.588943Z","time spent":"382.95035ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:54:44.071536Z","caller":"traceutil/trace.go:171","msg":"trace[1074368762] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"282.574063ms","start":"2026-07-25T06:54:43.788917Z","end":"2026-07-25T06:54:44.071491Z","steps":["trace[1074368762] 'process raft request' (duration: 282.194801ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:44.071548Z","caller":"traceutil/trace.go:171","msg":"trace[2034196803] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"268.817652ms","start":"2026-07-25T06:54:43.802686Z","end":"2026-07-25T06:54:44.071504Z","steps":["trace[2034196803] 'process raft request' (duration: 268.609585ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:44.071545Z","caller":"traceutil/trace.go:171","msg":"trace[1093791794] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2561; }","duration":"154.546937ms","start":"2026-07-25T06:54:43.916963Z","end":"2026-07-25T06:54:44.07151Z","steps":["trace[1093791794] 'read index received' (duration: 54.713914ms)","trace[1093791794] 'applied index is now lower than readState.Index' (duration: 99.831263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:54:44.071841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.851347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-07-25T06:54:44.071955Z","caller":"traceutil/trace.go:171","msg":"trace[1775782530] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2462; }","duration":"155.043113ms","start":"2026-07-25T06:54:43.9169Z","end":"2026-07-25T06:54:44.071943Z","steps":["trace[1775782530] 'agreement among raft nodes before linearized reading' (duration: 154.720563ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:44.07199Z","caller":"traceutil/trace.go:171","msg":"trace[756500302] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"254.010457ms","start":"2026-07-25T06:54:43.81793Z","end":"2026-07-25T06:54:44.07194Z","steps":["trace[756500302] 'process raft request' (duration: 253.47218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:44.072163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.323677ms","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-07-25T06:54:44.072216Z","caller":"traceutil/trace.go:171","msg":"trace[1857796549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2462; }","duration":"143.41206ms","start":"2026-07-25T06:54:43.928789Z","end":"2026-07-25T06:54:44.072201Z","steps":["trace[1857796549] 'agreement among raft nodes before linearized reading' (duration: 143.057009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:54:44.07242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.449249ms","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-07-25T06:54:44.072461Z","caller":"traceutil/trace.go:171","msg":"trace[771484929] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2462; }","duration":"108.538622ms","start":"2026-07-25T06:54:43.963914Z","end":"2026-07-25T06:54:44.072453Z","steps":["trace[771484929] 'agreement among raft nodes before linearized reading' (duration: 108.446639ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:44.072435Z","caller":"traceutil/trace.go:171","msg":"trace[932144307] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"255.761133ms","start":"2026-07-25T06:54:43.816615Z","end":"2026-07-25T06:54:44.072376Z","steps":["trace[932144307] 'process raft request' (duration: 254.73691ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:44.072715Z","caller":"traceutil/trace.go:171","msg":"trace[109818949] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"214.986965ms","start":"2026-07-25T06:54:43.857714Z","end":"2026-07-25T06:54:44.072701Z","steps":["trace[109818949] 'process raft request' (duration: 213.737175ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:54:54.4217Z","caller":"traceutil/trace.go:171","msg":"trace[1632365360] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"108.430007ms","start":"2026-07-25T06:54:54.313235Z","end":"2026-07-25T06:54:54.421665Z","steps":["trace[1632365360] 'process raft request' (duration: 108.075936ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:04.467607Z","caller":"traceutil/trace.go:171","msg":"trace[2060939587] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"294.046487ms","start":"2026-07-25T06:55:04.173525Z","end":"2026-07-25T06:55:04.467571Z","steps":["trace[2060939587] 'process raft request' (duration: 293.827311ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:04.467574Z","caller":"traceutil/trace.go:171","msg":"trace[1888646700] linearizableReadLoop","detail":"{readStateIndex:2694; appliedIndex:2693; }","duration":"284.524956ms","start":"2026-07-25T06:55:04.183019Z","end":"2026-07-25T06:55:04.467544Z","steps":["trace[1888646700] 'read index received' (duration: 284.336911ms)","trace[1888646700] 'applied index is now lower than readState.Index' (duration: 186.295µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:04.467766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.727482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-07-25T06:55:04.467814Z","caller":"traceutil/trace.go:171","msg":"trace[583638035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2585; }","duration":"284.824715ms","start":"2026-07-25T06:55:04.182974Z","end":"2026-07-25T06:55:04.467798Z","steps":["trace[583638035] 'agreement among raft nodes before linearized reading' (duration: 284.634349ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:04.621385Z","caller":"traceutil/trace.go:171","msg":"trace[206569781] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"420.925101ms","start":"2026-07-25T06:55:04.200415Z","end":"2026-07-25T06:55:04.62134Z","steps":["trace[206569781] 'process raft request' (duration: 420.827028ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:04.62142Z","caller":"traceutil/trace.go:171","msg":"trace[434095457] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"424.922028ms","start":"2026-07-25T06:55:04.196446Z","end":"2026-07-25T06:55:04.621368Z","steps":["trace[434095457] 'process raft request' (duration: 424.596209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.621648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:04.196418Z","time spent":"425.106614ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:04.62183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:04.200399Z","time spent":"421.077716ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:04.621769Z","caller":"traceutil/trace.go:171","msg":"trace[1910406872] linearizableReadLoop","detail":"{readStateIndex:2697; appliedIndex:2694; }","duration":"154.052836ms","start":"2026-07-25T06:55:04.467674Z","end":"2026-07-25T06:55:04.621727Z","steps":["trace[1910406872] 'read index received' (duration: 153.279363ms)","trace[1910406872] 'applied index is now lower than readState.Index' (duration: 772.243µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:04.622115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.168756ms","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-07-25T06:55:04.622171Z","caller":"traceutil/trace.go:171","msg":"trace[1379254128] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2588; }","duration":"405.226898ms","start":"2026-07-25T06:55:04.216928Z","end":"2026-07-25T06:55:04.622155Z","steps":["trace[1379254128] 'agreement among raft nodes before linearized reading' (duration: 404.95693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.622204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:04.216914Z","time spent":"405.282319ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T06:55:04.62221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.110831ms","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-07-25T06:55:04.622248Z","caller":"traceutil/trace.go:171","msg":"trace[175150763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2588; }","duration":"148.170822ms","start":"2026-07-25T06:55:04.474066Z","end":"2026-07-25T06:55:04.622236Z","steps":["trace[175150763] 'agreement among raft nodes before linearized reading' (duration: 148.026738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.622378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.037591ms","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-07-25T06:55:04.622413Z","caller":"traceutil/trace.go:171","msg":"trace[200262568] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2588; }","duration":"142.091163ms","start":"2026-07-25T06:55:04.480313Z","end":"2026-07-25T06:55:04.622405Z","steps":["trace[200262568] 'agreement among raft nodes before linearized reading' (duration: 142.033321ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:04.622477Z","caller":"traceutil/trace.go:171","msg":"trace[102795605] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"416.307425ms","start":"2026-07-25T06:55:04.206157Z","end":"2026-07-25T06:55:04.622465Z","steps":["trace[102795605] 'process raft request' (duration: 415.13532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.62257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:04.206148Z","time spent":"416.372696ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:04.623023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.991809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-25T06:55:04.62309Z","caller":"traceutil/trace.go:171","msg":"trace[145630799] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2588; }","duration":"226.098152ms","start":"2026-07-25T06:55:04.396975Z","end":"2026-07-25T06:55:04.623073Z","steps":["trace[145630799] 'agreement among raft nodes before linearized reading' (duration: 225.533455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.948802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.165697ms","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-07-25T06:55:04.948914Z","caller":"traceutil/trace.go:171","msg":"trace[1394354881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2590; }","duration":"160.344411ms","start":"2026-07-25T06:55:04.788543Z","end":"2026-07-25T06:55:04.948887Z","steps":["trace[1394354881] 'range keys from in-memory index tree' (duration: 159.94866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.948962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.834582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:55:04.949054Z","caller":"traceutil/trace.go:171","msg":"trace[1844963552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2590; }","duration":"189.994106ms","start":"2026-07-25T06:55:04.759036Z","end":"2026-07-25T06:55:04.94903Z","steps":["trace[1844963552] 'range keys from in-memory index tree' (duration: 189.481612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:04.949253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.203981ms","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-07-25T06:55:04.94928Z","caller":"traceutil/trace.go:171","msg":"trace[230288399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2590; }","duration":"194.280113ms","start":"2026-07-25T06:55:04.754989Z","end":"2026-07-25T06:55:04.94927Z","steps":["trace[230288399] 'range keys from in-memory index tree' (duration: 193.453489ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:06.09776Z","caller":"traceutil/trace.go:171","msg":"trace[81087218] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"288.581433ms","start":"2026-07-25T06:55:05.809145Z","end":"2026-07-25T06:55:06.097727Z","steps":["trace[81087218] 'process raft request' (duration: 288.380047ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:06.613032Z","caller":"traceutil/trace.go:171","msg":"trace[532586203] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"138.651909ms","start":"2026-07-25T06:55:06.474346Z","end":"2026-07-25T06:55:06.612998Z","steps":["trace[532586203] 'process raft request' (duration: 138.425023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.1389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578888027475686123,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T06:55:07.55477Z","caller":"traceutil/trace.go:171","msg":"trace[785867118] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"957.864982ms","start":"2026-07-25T06:55:06.596871Z","end":"2026-07-25T06:55:07.554736Z","steps":["trace[785867118] 'process raft request' (duration: 908.262855ms)","trace[785867118] 'compare' (duration: 49.005879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:07.554946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.596843Z","time spent":"958.010066ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:07.575531Z","caller":"traceutil/trace.go:171","msg":"trace[185423821] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"948.431154ms","start":"2026-07-25T06:55:06.627061Z","end":"2026-07-25T06:55:07.575493Z","steps":["trace[185423821] 'process raft request' (duration: 948.098124ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:07.575542Z","caller":"traceutil/trace.go:171","msg":"trace[1548540914] linearizableReadLoop","detail":"{readStateIndex:2709; appliedIndex:2705; }","duration":"937.321938ms","start":"2026-07-25T06:55:06.638183Z","end":"2026-07-25T06:55:07.575505Z","steps":["trace[1548540914] 'read index received' (duration: 866.995166ms)","trace[1548540914] 'applied index is now lower than readState.Index' (duration: 70.324962ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:07.575629Z","caller":"traceutil/trace.go:171","msg":"trace[863950318] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"946.066994ms","start":"2026-07-25T06:55:06.629537Z","end":"2026-07-25T06:55:07.575604Z","steps":["trace[863950318] 'process raft request' (duration: 945.869728ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:07.575621Z","caller":"traceutil/trace.go:171","msg":"trace[433536765] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"947.261649ms","start":"2026-07-25T06:55:06.628319Z","end":"2026-07-25T06:55:07.575581Z","steps":["trace[433536765] 'process raft request' (duration: 947.037983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.575719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.627035Z","time spent":"948.581618ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:07.575742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.629525Z","time spent":"946.156137ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:07.575807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.593117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T06:55:07.575811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.628285Z","time spent":"947.411573ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:07.575857Z","caller":"traceutil/trace.go:171","msg":"trace[1564977089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2599; }","duration":"937.668629ms","start":"2026-07-25T06:55:06.638174Z","end":"2026-07-25T06:55:07.575842Z","steps":["trace[1564977089] 'agreement among raft nodes before linearized reading' (duration: 937.485273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.5759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.638104Z","time spent":"937.782082ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:55:07.576716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.722798ms","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-07-25T06:55:07.576776Z","caller":"traceutil/trace.go:171","msg":"trace[1639708616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2599; }","duration":"589.805741ms","start":"2026-07-25T06:55:06.986956Z","end":"2026-07-25T06:55:07.576762Z","steps":["trace[1639708616] 'agreement among raft nodes before linearized reading' (duration: 589.619675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.576729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.341659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:55:07.576812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.986942Z","time spent":"589.863413ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T06:55:07.576829Z","caller":"traceutil/trace.go:171","msg":"trace[640411858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2599; }","duration":"474.471523ms","start":"2026-07-25T06:55:07.102343Z","end":"2026-07-25T06:55:07.576815Z","steps":["trace[640411858] 'agreement among raft nodes before linearized reading' (duration: 474.241796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.576808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.321277ms","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-07-25T06:55:07.576906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:07.102325Z","time spent":"474.570247ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T06:55:07.577027Z","caller":"traceutil/trace.go:171","msg":"trace[128309197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2599; }","duration":"602.552754ms","start":"2026-07-25T06:55:06.974413Z","end":"2026-07-25T06:55:07.576966Z","steps":["trace[128309197] 'agreement among raft nodes before linearized reading' (duration: 602.336577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.577042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.670868ms","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-07-25T06:55:07.577072Z","caller":"traceutil/trace.go:171","msg":"trace[722144687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2599; }","duration":"588.729909ms","start":"2026-07-25T06:55:06.988333Z","end":"2026-07-25T06:55:07.577063Z","steps":["trace[722144687] 'agreement among raft nodes before linearized reading' (duration: 588.634697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.577108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.988322Z","time spent":"588.779591ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T06:55:07.576719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.651739ms","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-07-25T06:55:07.577193Z","caller":"traceutil/trace.go:171","msg":"trace[367563722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2599; }","duration":"936.162384ms","start":"2026-07-25T06:55:06.641016Z","end":"2026-07-25T06:55:07.577179Z","steps":["trace[367563722] 'agreement among raft nodes before linearized reading' (duration: 935.574277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.577284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.640959Z","time spent":"936.311248ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T06:55:07.577356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.213011ms","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-07-25T06:55:07.57746Z","caller":"traceutil/trace.go:171","msg":"trace[323445172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2599; }","duration":"914.426657ms","start":"2026-07-25T06:55:06.663011Z","end":"2026-07-25T06:55:07.577437Z","steps":["trace[323445172] 'agreement among raft nodes before linearized reading' (duration: 914.075277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.57752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.662977Z","time spent":"914.53251ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T06:55:07.57789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:06.974391Z","time spent":"602.674237ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T06:55:07.799874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.875869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:55:07.800077Z","caller":"traceutil/trace.go:171","msg":"trace[533137607] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"216.533767ms","start":"2026-07-25T06:55:07.58352Z","end":"2026-07-25T06:55:07.800053Z","steps":["trace[533137607] 'process raft request' (duration: 111.288937ms)","trace[533137607] 'compare' (duration: 103.736656ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:07.800861Z","caller":"traceutil/trace.go:171","msg":"trace[986075938] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"217.194296ms","start":"2026-07-25T06:55:07.58362Z","end":"2026-07-25T06:55:07.800815Z","steps":["trace[986075938] 'process raft request' (duration: 216.361082ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:07.801156Z","caller":"traceutil/trace.go:171","msg":"trace[1000459524] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2710; }","duration":"141.932671ms","start":"2026-07-25T06:55:07.659192Z","end":"2026-07-25T06:55:07.801124Z","steps":["trace[1000459524] 'read index received' (duration: 35.631229ms)","trace[1000459524] 'applied index is now lower than readState.Index' (duration: 106.299471ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:07.801295Z","caller":"traceutil/trace.go:171","msg":"trace[1333560011] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"217.222937ms","start":"2026-07-25T06:55:07.583994Z","end":"2026-07-25T06:55:07.801217Z","steps":["trace[1333560011] 'process raft request' (duration: 216.360522ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:07.80194Z","caller":"traceutil/trace.go:171","msg":"trace[1390886600] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"217.796594ms","start":"2026-07-25T06:55:07.584129Z","end":"2026-07-25T06:55:07.801926Z","steps":["trace[1390886600] 'process raft request' (duration: 216.903658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:07.802567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.371363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:55:07.802631Z","caller":"traceutil/trace.go:171","msg":"trace[474466112] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2604; }","duration":"143.486116ms","start":"2026-07-25T06:55:07.659126Z","end":"2026-07-25T06:55:07.802613Z","steps":["trace[474466112] 'agreement among raft nodes before linearized reading' (duration: 142.543858ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:08.114718Z","caller":"traceutil/trace.go:171","msg":"trace[138904845] linearizableReadLoop","detail":"{readStateIndex:2715; appliedIndex:2714; }","duration":"304.804051ms","start":"2026-07-25T06:55:07.809889Z","end":"2026-07-25T06:55:08.114693Z","steps":["trace[138904845] 'read index received' (duration: 277.765305ms)","trace[138904845] 'applied index is now lower than readState.Index' (duration: 27.037696ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:08.114826Z","caller":"traceutil/trace.go:171","msg":"trace[46861379] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"306.384077ms","start":"2026-07-25T06:55:07.808395Z","end":"2026-07-25T06:55:08.114779Z","steps":["trace[46861379] 'process raft request' (duration: 279.233368ms)","trace[46861379] 'compare' (duration: 26.588023ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:08.115018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.375701ms","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-07-25T06:55:08.115372Z","caller":"traceutil/trace.go:171","msg":"trace[1819921017] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2605; }","duration":"217.754651ms","start":"2026-07-25T06:55:07.897584Z","end":"2026-07-25T06:55:08.115338Z","steps":["trace[1819921017] 'agreement among raft nodes before linearized reading' (duration: 217.36656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:08.115228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.296396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-07-25T06:55:08.115571Z","caller":"traceutil/trace.go:171","msg":"trace[56735337] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5; range_end:; response_count:1; response_revision:2605; }","duration":"305.679247ms","start":"2026-07-25T06:55:07.809844Z","end":"2026-07-25T06:55:08.115523Z","steps":["trace[56735337] 'agreement among raft nodes before linearized reading' (duration: 305.015117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:08.115692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:07.809831Z","time spent":"305.81934ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1317,"request content":"key:\"/registry/persistentvolumes/pvc-7d001013-f0d9-479e-8b41-605d73c8e5e5\" "} {"level":"warn","ts":"2026-07-25T06:55:08.115287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:07.808373Z","time spent":"306.562732ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T06:55:08.527543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.11107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:55:08.527794Z","caller":"traceutil/trace.go:171","msg":"trace[1862329430] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2716; }","duration":"307.290092ms","start":"2026-07-25T06:55:08.220482Z","end":"2026-07-25T06:55:08.527773Z","steps":["trace[1862329430] 'read index received' (duration: 184.791281ms)","trace[1862329430] 'applied index is now lower than readState.Index' (duration: 122.496591ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:08.527823Z","caller":"traceutil/trace.go:171","msg":"trace[1560149723] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"327.429987ms","start":"2026-07-25T06:55:08.200353Z","end":"2026-07-25T06:55:08.527783Z","steps":["trace[1560149723] 'process raft request' (duration: 204.933656ms)","trace[1560149723] 'compare' (duration: 121.609985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:08.527985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.507809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-07-25T06:55:08.528004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:08.200323Z","time spent":"327.576791ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:08.528017Z","caller":"traceutil/trace.go:171","msg":"trace[745802207] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2607; }","duration":"307.59133ms","start":"2026-07-25T06:55:08.220414Z","end":"2026-07-25T06:55:08.528005Z","steps":["trace[745802207] 'agreement among raft nodes before linearized reading' (duration: 307.412175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:08.52806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:08.220388Z","time spent":"307.663603ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-07-25T06:55:08.957787Z","caller":"traceutil/trace.go:171","msg":"trace[1805541714] linearizableReadLoop","detail":"{readStateIndex:2718; appliedIndex:2717; }","duration":"245.380463ms","start":"2026-07-25T06:55:08.712373Z","end":"2026-07-25T06:55:08.957754Z","steps":["trace[1805541714] 'read index received' (duration: 147.603661ms)","trace[1805541714] 'applied index is now lower than readState.Index' (duration: 97.775512ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:08.957871Z","caller":"traceutil/trace.go:171","msg":"trace[20106326] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"337.925703ms","start":"2026-07-25T06:55:08.619852Z","end":"2026-07-25T06:55:08.957778Z","steps":["trace[20106326] 'process raft request' (duration: 240.019367ms)","trace[20106326] 'compare' (duration: 97.757252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:08.957922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.524487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:55:08.957962Z","caller":"traceutil/trace.go:171","msg":"trace[918044514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2608; }","duration":"245.62146ms","start":"2026-07-25T06:55:08.71233Z","end":"2026-07-25T06:55:08.957951Z","steps":["trace[918044514] 'agreement among raft nodes before linearized reading' (duration: 245.529007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:08.958056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:08.61982Z","time spent":"338.123779ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:09.321068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.331157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:55:09.321523Z","caller":"traceutil/trace.go:171","msg":"trace[66750711] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2608; }","duration":"147.623614ms","start":"2026-07-25T06:55:09.173625Z","end":"2026-07-25T06:55:09.321249Z","steps":["trace[66750711] 'count revisions from in-memory index tree' (duration: 147.138091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.09397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578888027475686152,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T06:55:10.112744Z","caller":"traceutil/trace.go:171","msg":"trace[1241650779] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"528.811413ms","start":"2026-07-25T06:55:09.583886Z","end":"2026-07-25T06:55:10.112697Z","steps":["trace[1241650779] 'process raft request' (duration: 528.599287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.112982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.583864Z","time spent":"528.994268ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:10.212431Z","caller":"traceutil/trace.go:171","msg":"trace[1421318753] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"628.303813ms","start":"2026-07-25T06:55:09.584094Z","end":"2026-07-25T06:55:10.212397Z","steps":["trace[1421318753] 'process raft request' (duration: 549.86847ms)","trace[1421318753] 'compare' (duration: 78.237947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:55:10.212643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.584065Z","time spent":"628.465936ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:10.212991Z","caller":"traceutil/trace.go:171","msg":"trace[1624966484] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"628.691934ms","start":"2026-07-25T06:55:09.584263Z","end":"2026-07-25T06:55:10.212955Z","steps":["trace[1624966484] 'process raft request' (duration: 628.078306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.213205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.584252Z","time spent":"628.825907ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:55:10.213954Z","caller":"traceutil/trace.go:171","msg":"trace[371340] linearizableReadLoop","detail":"{readStateIndex:2722; appliedIndex:2719; }","duration":"620.046823ms","start":"2026-07-25T06:55:09.593892Z","end":"2026-07-25T06:55:10.213938Z","steps":["trace[371340] 'read index received' (duration: 518.752561ms)","trace[371340] 'applied index is now lower than readState.Index' (duration: 101.292692ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:55:10.21409Z","caller":"traceutil/trace.go:171","msg":"trace[1057235479] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"596.552063ms","start":"2026-07-25T06:55:09.617526Z","end":"2026-07-25T06:55:10.214078Z","steps":["trace[1057235479] 'process raft request' (duration: 595.312767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.21417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.617508Z","time spent":"596.618454ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:55:10.214698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.816759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:55:10.214763Z","caller":"traceutil/trace.go:171","msg":"trace[188824982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2612; }","duration":"501.925182ms","start":"2026-07-25T06:55:09.712823Z","end":"2026-07-25T06:55:10.214748Z","steps":["trace[188824982] 'agreement among raft nodes before linearized reading' (duration: 501.822649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.214797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.712801Z","time spent":"501.988864ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T06:55:10.214797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.404248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T06:55:10.214868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.566347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-07-25T06:55:10.214884Z","caller":"traceutil/trace.go:171","msg":"trace[1350218664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2612; }","duration":"587.559582ms","start":"2026-07-25T06:55:09.627305Z","end":"2026-07-25T06:55:10.214865Z","steps":["trace[1350218664] 'agreement among raft nodes before linearized reading' (duration: 587.276864ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:55:10.214899Z","caller":"traceutil/trace.go:171","msg":"trace[1665112880] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2612; }","duration":"573.624968ms","start":"2026-07-25T06:55:09.641265Z","end":"2026-07-25T06:55:10.21489Z","steps":["trace[1665112880] 'agreement among raft nodes before linearized reading' (duration: 573.482184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.214936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.641253Z","time spent":"573.6759ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12410,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-25T06:55:10.21494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.62728Z","time spent":"587.651015ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:55:10.214694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.770303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:55:10.215009Z","caller":"traceutil/trace.go:171","msg":"trace[431374214] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2612; }","duration":"497.076102ms","start":"2026-07-25T06:55:09.71791Z","end":"2026-07-25T06:55:10.214986Z","steps":["trace[431374214] 'agreement among raft nodes before linearized reading' (duration: 496.716742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.215055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.71789Z","time spent":"497.151634ms","remote":"127.0.0.1:44274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-25T06:55:10.215148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.415745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T06:55:10.215154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.712204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T06:55:10.215187Z","caller":"traceutil/trace.go:171","msg":"trace[924055881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2612; }","duration":"408.487247ms","start":"2026-07-25T06:55:09.806686Z","end":"2026-07-25T06:55:10.215174Z","steps":["trace[924055881] 'agreement among raft nodes before linearized reading' (duration: 408.399604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.215226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.806669Z","time spent":"408.550528ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T06:55:10.215239Z","caller":"traceutil/trace.go:171","msg":"trace[1961533949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2612; }","duration":"407.879159ms","start":"2026-07-25T06:55:09.807337Z","end":"2026-07-25T06:55:10.215216Z","steps":["trace[1961533949] 'agreement among raft nodes before linearized reading' (duration: 407.590971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.215293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.807326Z","time spent":"407.951231ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T06:55:10.215398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.474904ms","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-07-25T06:55:10.215434Z","caller":"traceutil/trace.go:171","msg":"trace[1566245608] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2612; }","duration":"621.567147ms","start":"2026-07-25T06:55:09.593856Z","end":"2026-07-25T06:55:10.215423Z","steps":["trace[1566245608] 'agreement among raft nodes before linearized reading' (duration: 621.451434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.215456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.593839Z","time spent":"621.612588ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T06:55:10.215623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.099244ms","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-07-25T06:55:10.215675Z","caller":"traceutil/trace.go:171","msg":"trace[1462723443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2612; }","duration":"409.150356ms","start":"2026-07-25T06:55:09.806512Z","end":"2026-07-25T06:55:10.215662Z","steps":["trace[1462723443] 'agreement among raft nodes before linearized reading' (duration: 408.072855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.215745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.806404Z","time spent":"409.332941ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T06:55:10.215638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.117204ms","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-07-25T06:55:10.216866Z","caller":"traceutil/trace.go:171","msg":"trace[662675500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2612; }","duration":"410.454853ms","start":"2026-07-25T06:55:09.806395Z","end":"2026-07-25T06:55:10.21685Z","steps":["trace[662675500] 'agreement among raft nodes before linearized reading' (duration: 408.337902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:55:10.216914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:55:09.806367Z","time spent":"410.535016ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T06:56:34.89806Z","caller":"traceutil/trace.go:171","msg":"trace[1582235080] linearizableReadLoop","detail":"{readStateIndex:3307; appliedIndex:3306; }","duration":"156.25644ms","start":"2026-07-25T06:56:34.741767Z","end":"2026-07-25T06:56:34.898023Z","steps":["trace[1582235080] 'read index received' (duration: 156.043995ms)","trace[1582235080] 'applied index is now lower than readState.Index' (duration: 209.875µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:34.898205Z","caller":"traceutil/trace.go:171","msg":"trace[1715939808] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"156.438654ms","start":"2026-07-25T06:56:34.741752Z","end":"2026-07-25T06:56:34.898191Z","steps":["trace[1715939808] 'process raft request' (duration: 155.985093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:34.898756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.832263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T06:56:34.899806Z","caller":"traceutil/trace.go:171","msg":"trace[415695315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3179; }","duration":"158.05942ms","start":"2026-07-25T06:56:34.741721Z","end":"2026-07-25T06:56:34.899781Z","steps":["trace[415695315] 'agreement among raft nodes before linearized reading' (duration: 156.605548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.399128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578888027475687474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T06:56:35.561529Z","caller":"traceutil/trace.go:171","msg":"trace[630594072] linearizableReadLoop","detail":"{readStateIndex:3311; appliedIndex:3307; }","duration":"663.357132ms","start":"2026-07-25T06:56:34.898151Z","end":"2026-07-25T06:56:35.561508Z","steps":["trace[630594072] 'read index received' (duration: 660.734394ms)","trace[630594072] 'applied index is now lower than readState.Index' (duration: 2.621828ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:35.561648Z","caller":"traceutil/trace.go:171","msg":"trace[243600571] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"657.028611ms","start":"2026-07-25T06:56:34.904608Z","end":"2026-07-25T06:56:35.561637Z","steps":["trace[243600571] 'process raft request' (duration: 656.846627ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:35.561621Z","caller":"traceutil/trace.go:171","msg":"trace[2061367606] transaction","detail":"{read_only:false; response_revision:3181; number_of_response:1; }","duration":"819.638812ms","start":"2026-07-25T06:56:34.741946Z","end":"2026-07-25T06:56:35.561585Z","steps":["trace[2061367606] 'process raft request' (duration: 819.442498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.561743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:34.904586Z","time spent":"657.080902ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:56:35.561871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.713393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-25T06:56:35.561894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:34.741911Z","time spent":"819.809266ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T06:56:35.561936Z","caller":"traceutil/trace.go:171","msg":"trace[692474317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3183; }","duration":"814.821025ms","start":"2026-07-25T06:56:34.747102Z","end":"2026-07-25T06:56:35.561923Z","steps":["trace[692474317] 'agreement among raft nodes before linearized reading' (duration: 814.571069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.561965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:34.747082Z","time spent":"814.878477ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T06:56:35.562008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.155337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:56:35.562013Z","caller":"traceutil/trace.go:171","msg":"trace[628765114] transaction","detail":"{read_only:false; response_revision:3180; number_of_response:1; }","duration":"820.127423ms","start":"2026-07-25T06:56:34.741856Z","end":"2026-07-25T06:56:35.561983Z","steps":["trace[628765114] 'process raft request' (duration: 817.011004ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:35.562047Z","caller":"traceutil/trace.go:171","msg":"trace[101471354] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3183; }","duration":"318.25468ms","start":"2026-07-25T06:56:35.243784Z","end":"2026-07-25T06:56:35.562039Z","steps":["trace[101471354] 'agreement among raft nodes before linearized reading' (duration: 318.175748ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:35.562066Z","caller":"traceutil/trace.go:171","msg":"trace[1361985165] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"259.168337ms","start":"2026-07-25T06:56:35.30289Z","end":"2026-07-25T06:56:35.562058Z","steps":["trace[1361985165] 'process raft request' (duration: 258.596604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.562069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:35.243762Z","time spent":"318.300421ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":2,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-25T06:56:35.562076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:34.741851Z","time spent":"820.197095ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:56:35.562243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.531384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:56:35.562268Z","caller":"traceutil/trace.go:171","msg":"trace[1868120553] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3183; }","duration":"430.588585ms","start":"2026-07-25T06:56:35.131669Z","end":"2026-07-25T06:56:35.562258Z","steps":["trace[1868120553] 'agreement among raft nodes before linearized reading' (duration: 430.539544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.562305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:35.131648Z","time spent":"430.636186ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-25T06:56:35.562482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.405833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad63998a-8bca-4f0e-9176-471ce41299ca\" ","response":"range_response_count:1 size:3939"} {"level":"info","ts":"2026-07-25T06:56:35.56252Z","caller":"traceutil/trace.go:171","msg":"trace[562560227] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad63998a-8bca-4f0e-9176-471ce41299ca; range_end:; response_count:1; response_revision:3183; }","duration":"458.495765ms","start":"2026-07-25T06:56:35.104015Z","end":"2026-07-25T06:56:35.562511Z","steps":["trace[562560227] 'agreement among raft nodes before linearized reading' (duration: 457.870811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:35.562538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:35.103977Z","time spent":"458.555027ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3963,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad63998a-8bca-4f0e-9176-471ce41299ca\" "} {"level":"info","ts":"2026-07-25T06:56:40.739853Z","caller":"traceutil/trace.go:171","msg":"trace[113132708] transaction","detail":"{read_only:false; response_revision:3230; number_of_response:1; }","duration":"225.360036ms","start":"2026-07-25T06:56:40.514464Z","end":"2026-07-25T06:56:40.739824Z","steps":["trace[113132708] 'process raft request' (duration: 225.177902ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:40.764189Z","caller":"traceutil/trace.go:171","msg":"trace[674089689] transaction","detail":"{read_only:false; response_revision:3231; number_of_response:1; }","duration":"190.377528ms","start":"2026-07-25T06:56:40.573781Z","end":"2026-07-25T06:56:40.764158Z","steps":["trace[674089689] 'process raft request' (duration: 190.056021ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:40.764215Z","caller":"traceutil/trace.go:171","msg":"trace[1299721769] transaction","detail":"{read_only:false; response_revision:3232; number_of_response:1; }","duration":"190.050301ms","start":"2026-07-25T06:56:40.574146Z","end":"2026-07-25T06:56:40.764196Z","steps":["trace[1299721769] 'process raft request' (duration: 189.777815ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:46.046313Z","caller":"traceutil/trace.go:171","msg":"trace[1932579293] linearizableReadLoop","detail":"{readStateIndex:3396; appliedIndex:3395; }","duration":"435.518043ms","start":"2026-07-25T06:56:45.61076Z","end":"2026-07-25T06:56:46.046278Z","steps":["trace[1932579293] 'read index received' (duration: 435.219846ms)","trace[1932579293] 'applied index is now lower than readState.Index' (duration: 296.387µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:46.046616Z","caller":"traceutil/trace.go:171","msg":"trace[60814650] transaction","detail":"{read_only:false; response_revision:3265; number_of_response:1; }","duration":"442.415202ms","start":"2026-07-25T06:56:45.604147Z","end":"2026-07-25T06:56:46.046562Z","steps":["trace[60814650] 'process raft request' (duration: 441.894211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.046844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.604123Z","time spent":"442.608767ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T06:56:46.046917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.52098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T06:56:46.047028Z","caller":"traceutil/trace.go:171","msg":"trace[1610564160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3265; }","duration":"433.707923ms","start":"2026-07-25T06:56:45.613293Z","end":"2026-07-25T06:56:46.047001Z","steps":["trace[1610564160] 'agreement among raft nodes before linearized reading' (duration: 433.341886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.047094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.613251Z","time spent":"433.825556ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T06:56:46.047361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.563455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T06:56:46.047415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.324542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-25T06:56:46.047468Z","caller":"traceutil/trace.go:171","msg":"trace[1860287801] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3265; }","duration":"301.413704ms","start":"2026-07-25T06:56:45.74604Z","end":"2026-07-25T06:56:46.047454Z","steps":["trace[1860287801] 'agreement among raft nodes before linearized reading' (duration: 301.244281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.047468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.698038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T06:56:46.047509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.746016Z","time spent":"301.486026ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-25T06:56:46.047523Z","caller":"traceutil/trace.go:171","msg":"trace[417363937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3265; }","duration":"335.79992ms","start":"2026-07-25T06:56:45.711708Z","end":"2026-07-25T06:56:46.047508Z","steps":["trace[417363937] 'agreement among raft nodes before linearized reading' (duration: 335.699138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.047578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.711681Z","time spent":"335.885942ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T06:56:46.047425Z","caller":"traceutil/trace.go:171","msg":"trace[1291987882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3265; }","duration":"436.692598ms","start":"2026-07-25T06:56:45.610717Z","end":"2026-07-25T06:56:46.04741Z","steps":["trace[1291987882] 'agreement among raft nodes before linearized reading' (duration: 435.806919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.047819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.610698Z","time spent":"437.044316ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T06:56:46.047893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.312414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-07-25T06:56:46.047923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.274977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T06:56:46.047983Z","caller":"traceutil/trace.go:171","msg":"trace[2139618956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3265; }","duration":"429.353289ms","start":"2026-07-25T06:56:45.618616Z","end":"2026-07-25T06:56:46.047969Z","steps":["trace[2139618956] 'agreement among raft nodes before linearized reading' (duration: 429.205456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.048023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.752915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-25T06:56:46.048031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.618607Z","time spent":"429.413311ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T06:56:46.048059Z","caller":"traceutil/trace.go:171","msg":"trace[1262754131] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3265; }","duration":"160.870127ms","start":"2026-07-25T06:56:45.887181Z","end":"2026-07-25T06:56:46.048051Z","steps":["trace[1262754131] 'agreement among raft nodes before linearized reading' (duration: 160.774035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.048404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.585641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T06:56:46.048455Z","caller":"traceutil/trace.go:171","msg":"trace[1689106781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3265; }","duration":"433.661613ms","start":"2026-07-25T06:56:45.614779Z","end":"2026-07-25T06:56:46.04844Z","steps":["trace[1689106781] 'agreement among raft nodes before linearized reading' (duration: 433.52667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.048502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:45.614766Z","time spent":"433.719854ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T06:56:46.047935Z","caller":"traceutil/trace.go:171","msg":"trace[1254170206] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3265; }","duration":"106.398036ms","start":"2026-07-25T06:56:45.941528Z","end":"2026-07-25T06:56:46.047926Z","steps":["trace[1254170206] 'agreement among raft nodes before linearized reading' (duration: 106.295993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.411644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.262848ms","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-07-25T06:56:46.411736Z","caller":"traceutil/trace.go:171","msg":"trace[1466860444] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3269; }","duration":"252.40064ms","start":"2026-07-25T06:56:46.159313Z","end":"2026-07-25T06:56:46.411714Z","steps":["trace[1466860444] 'range keys from in-memory index tree' (duration: 252.121705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.844291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.085253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-25T06:56:46.844559Z","caller":"traceutil/trace.go:171","msg":"trace[973887620] linearizableReadLoop","detail":"{readStateIndex:3401; appliedIndex:3400; }","duration":"428.865568ms","start":"2026-07-25T06:56:46.415613Z","end":"2026-07-25T06:56:46.844479Z","steps":["trace[973887620] 'read index received' (duration: 97.506489ms)","trace[973887620] 'applied index is now lower than readState.Index' (duration: 331.357829ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:56:46.844796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.149745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:56:46.844915Z","caller":"traceutil/trace.go:171","msg":"trace[1827036950] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3269; }","duration":"429.364208ms","start":"2026-07-25T06:56:46.415523Z","end":"2026-07-25T06:56:46.844887Z","steps":["trace[1827036950] 'agreement among raft nodes before linearized reading' (duration: 429.097133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:46.844966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:46.415501Z","time spent":"429.455751ms","remote":"127.0.0.1:44138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-25T06:56:47.060778Z","caller":"traceutil/trace.go:171","msg":"trace[2140616616] transaction","detail":"{read_only:false; response_revision:3270; number_of_response:1; }","duration":"294.894377ms","start":"2026-07-25T06:56:46.765837Z","end":"2026-07-25T06:56:47.060732Z","steps":["trace[2140616616] 'process raft request' (duration: 294.641771ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.079623Z","caller":"traceutil/trace.go:171","msg":"trace[1867762872] linearizableReadLoop","detail":"{readStateIndex:3404; appliedIndex:3401; }","duration":"234.95287ms","start":"2026-07-25T06:56:46.844643Z","end":"2026-07-25T06:56:47.079596Z","steps":["trace[1867762872] 'read index received' (duration: 215.96223ms)","trace[1867762872] 'applied index is now lower than readState.Index' (duration: 18.98955ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:47.079725Z","caller":"traceutil/trace.go:171","msg":"trace[1152928536] transaction","detail":"{read_only:false; response_revision:3272; number_of_response:1; }","duration":"295.937758ms","start":"2026-07-25T06:56:46.783745Z","end":"2026-07-25T06:56:47.079676Z","steps":["trace[1152928536] 'process raft request' (duration: 295.801755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:47.07994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.952974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:56:47.080001Z","caller":"traceutil/trace.go:171","msg":"trace[529702825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3272; }","duration":"367.23146ms","start":"2026-07-25T06:56:46.712754Z","end":"2026-07-25T06:56:47.079985Z","steps":["trace[529702825] 'agreement among raft nodes before linearized reading' (duration: 366.985335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:47.080003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.372754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-07-25T06:56:47.080036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.538599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-25T06:56:47.080056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T06:56:46.712721Z","time spent":"367.325503ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T06:56:47.079943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.413837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T06:56:47.080091Z","caller":"traceutil/trace.go:171","msg":"trace[1649660257] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:3272; }","duration":"282.547208ms","start":"2026-07-25T06:56:46.797523Z","end":"2026-07-25T06:56:47.080071Z","steps":["trace[1649660257] 'agreement among raft nodes before linearized reading' (duration: 282.16123ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.080137Z","caller":"traceutil/trace.go:171","msg":"trace[384831232] transaction","detail":"{read_only:false; response_revision:3271; number_of_response:1; }","duration":"297.353458ms","start":"2026-07-25T06:56:46.782774Z","end":"2026-07-25T06:56:47.080127Z","steps":["trace[384831232] 'process raft request' (duration: 296.668894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:47.080056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.702709ms","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-07-25T06:56:47.080176Z","caller":"traceutil/trace.go:171","msg":"trace[609491014] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3272; }","duration":"129.712102ms","start":"2026-07-25T06:56:46.950422Z","end":"2026-07-25T06:56:47.080134Z","steps":["trace[609491014] 'agreement among raft nodes before linearized reading' (duration: 129.441837ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.08029Z","caller":"traceutil/trace.go:171","msg":"trace[1960987498] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3272; }","duration":"232.005516ms","start":"2026-07-25T06:56:46.848232Z","end":"2026-07-25T06:56:47.080238Z","steps":["trace[1960987498] 'agreement among raft nodes before linearized reading' (duration: 231.589507ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.080091Z","caller":"traceutil/trace.go:171","msg":"trace[818896197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3272; }","duration":"129.655201ms","start":"2026-07-25T06:56:46.950421Z","end":"2026-07-25T06:56:47.080076Z","steps":["trace[818896197] 'agreement among raft nodes before linearized reading' (duration: 129.482827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:47.393569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.293277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T06:56:47.393999Z","caller":"traceutil/trace.go:171","msg":"trace[808046022] transaction","detail":"{read_only:false; response_revision:3276; number_of_response:1; }","duration":"141.506664ms","start":"2026-07-25T06:56:47.252473Z","end":"2026-07-25T06:56:47.39398Z","steps":["trace[808046022] 'process raft request' (duration: 141.343381ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.394123Z","caller":"traceutil/trace.go:171","msg":"trace[1103769150] linearizableReadLoop","detail":"{readStateIndex:3407; appliedIndex:3406; }","duration":"251.602822ms","start":"2026-07-25T06:56:47.142476Z","end":"2026-07-25T06:56:47.394079Z","steps":["trace[1103769150] 'read index received' (duration: 54.591269ms)","trace[1103769150] 'applied index is now lower than readState.Index' (duration: 197.009002ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:47.39411Z","caller":"traceutil/trace.go:171","msg":"trace[1393102286] transaction","detail":"{read_only:false; response_revision:3275; number_of_response:1; }","duration":"257.654782ms","start":"2026-07-25T06:56:47.136409Z","end":"2026-07-25T06:56:47.394063Z","steps":["trace[1393102286] 'process raft request' (duration: 60.759482ms)","trace[1393102286] 'compare' (duration: 196.104973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T06:56:47.394521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.02649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-25T06:56:47.394593Z","caller":"traceutil/trace.go:171","msg":"trace[382413792] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:3276; }","duration":"252.148773ms","start":"2026-07-25T06:56:47.142427Z","end":"2026-07-25T06:56:47.394576Z","steps":["trace[382413792] 'agreement among raft nodes before linearized reading' (duration: 251.740324ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:47.950792Z","caller":"traceutil/trace.go:171","msg":"trace[1709270423] linearizableReadLoop","detail":"{readStateIndex:3410; appliedIndex:3409; }","duration":"237.589969ms","start":"2026-07-25T06:56:47.713176Z","end":"2026-07-25T06:56:47.950766Z","steps":["trace[1709270423] 'read index received' (duration: 237.396905ms)","trace[1709270423] 'applied index is now lower than readState.Index' (duration: 192.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T06:56:47.951503Z","caller":"traceutil/trace.go:171","msg":"trace[150414829] transaction","detail":"{read_only:false; response_revision:3278; number_of_response:1; }","duration":"264.749985ms","start":"2026-07-25T06:56:47.686715Z","end":"2026-07-25T06:56:47.951465Z","steps":["trace[150414829] 'process raft request' (duration: 263.919277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T06:56:47.952889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.690984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T06:56:47.952981Z","caller":"traceutil/trace.go:171","msg":"trace[466366080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3278; }","duration":"239.828307ms","start":"2026-07-25T06:56:47.713133Z","end":"2026-07-25T06:56:47.952961Z","steps":["trace[466366080] 'agreement among raft nodes before linearized reading' (duration: 237.882535ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:49.210829Z","caller":"traceutil/trace.go:171","msg":"trace[177826319] transaction","detail":"{read_only:false; response_revision:3285; number_of_response:1; }","duration":"124.559256ms","start":"2026-07-25T06:56:49.086231Z","end":"2026-07-25T06:56:49.210791Z","steps":["trace[177826319] 'process raft request' (duration: 124.408013ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T06:56:49.210869Z","caller":"traceutil/trace.go:171","msg":"trace[1387785644] transaction","detail":"{read_only:false; response_revision:3286; number_of_response:1; }","duration":"124.331171ms","start":"2026-07-25T06:56:49.086505Z","end":"2026-07-25T06:56:49.210836Z","steps":["trace[1387785644] 'process raft request' (duration: 124.234609ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:00:48.130628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2817} {"level":"info","ts":"2026-07-25T07:00:48.20914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2817,"took":"76.746522ms","hash":3856856091,"current-db-size-bytes":37908480,"current-db-size":"38 MB","current-db-size-in-use-bytes":14340096,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-25T07:00:48.209226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3856856091,"revision":2817,"compact-revision":-1} {"level":"info","ts":"2026-07-25T07:02:59.715907Z","caller":"traceutil/trace.go:171","msg":"trace[965742060] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"107.690421ms","start":"2026-07-25T07:02:59.608192Z","end":"2026-07-25T07:02:59.715882Z","steps":["trace[965742060] 'process raft request' (duration: 107.570526ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:04:06.898318Z","caller":"traceutil/trace.go:171","msg":"trace[181801991] transaction","detail":"{read_only:false; response_revision:6760; number_of_response:1; }","duration":"141.586349ms","start":"2026-07-25T07:04:06.756702Z","end":"2026-07-25T07:04:06.898289Z","steps":["trace[181801991] 'process raft request' (duration: 141.424923ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:04:06.921734Z","caller":"traceutil/trace.go:171","msg":"trace[1598007387] transaction","detail":"{read_only:false; response_revision:6762; number_of_response:1; }","duration":"162.906631ms","start":"2026-07-25T07:04:06.758805Z","end":"2026-07-25T07:04:06.921712Z","steps":["trace[1598007387] 'process raft request' (duration: 162.835648ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:04:06.922003Z","caller":"traceutil/trace.go:171","msg":"trace[1966402903] linearizableReadLoop","detail":"{readStateIndex:7019; appliedIndex:7015; }","duration":"127.267491ms","start":"2026-07-25T07:04:06.794705Z","end":"2026-07-25T07:04:06.921973Z","steps":["trace[1966402903] 'read index received' (duration: 103.377354ms)","trace[1966402903] 'applied index is now lower than readState.Index' (duration: 23.888557ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:04:06.922076Z","caller":"traceutil/trace.go:171","msg":"trace[1704585042] transaction","detail":"{read_only:false; response_revision:6763; number_of_response:1; }","duration":"159.342537ms","start":"2026-07-25T07:04:06.76272Z","end":"2026-07-25T07:04:06.922063Z","steps":["trace[1704585042] 'process raft request' (duration: 158.963463ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:04:06.9222Z","caller":"traceutil/trace.go:171","msg":"trace[1726011256] transaction","detail":"{read_only:false; response_revision:6761; number_of_response:1; }","duration":"162.934652ms","start":"2026-07-25T07:04:06.758753Z","end":"2026-07-25T07:04:06.921688Z","steps":["trace[1726011256] 'process raft request' (duration: 162.742745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:04:06.922249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.49637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-25T07:04:06.922297Z","caller":"traceutil/trace.go:171","msg":"trace[538984290] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6763; }","duration":"127.612544ms","start":"2026-07-25T07:04:06.794674Z","end":"2026-07-25T07:04:06.922287Z","steps":["trace[538984290] 'agreement among raft nodes before linearized reading' (duration: 127.407917ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:05:48.137574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4845} {"level":"info","ts":"2026-07-25T07:05:48.214492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4845,"took":"74.986427ms","hash":1599286945,"current-db-size-bytes":37908480,"current-db-size":"38 MB","current-db-size-in-use-bytes":28307456,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-25T07:05:48.214547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1599286945,"revision":4845,"compact-revision":2817}