{"level":"warn","ts":"2026-06-17T15:14:41.91957Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:14:41.919646Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.236: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.236:2380","--initial-cluster=instance=https://199.19.213.236:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.236:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.236: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-06-17T15:14:41.919713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:14:41.919721Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-06-17T15:14:41.919738Z","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-06-17T15:14:41.920385Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"]} {"level":"info","ts":"2026-06-17T15:14:41.920456Z","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.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.236: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-06-17T15:14:41.92521Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.564791ms"} {"level":"info","ts":"2026-06-17T15:14:41.935356Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f20dfa43a2e4607","cluster-id":"85a3671ea9bffea4"} {"level":"info","ts":"2026-06-17T15:14:41.935482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T15:14:41.935523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 0"} {"level":"info","ts":"2026-06-17T15:14:41.935549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f20dfa43a2e4607 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T15:14:41.935569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 1"} {"level":"info","ts":"2026-06-17T15:14:41.935618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"warn","ts":"2026-06-17T15:14:41.94532Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T15:14:41.946799Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T15:14:41.948029Z","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-06-17T15:14:41.950691Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f20dfa43a2e4607","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T15:14:41.950814Z","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-06-17T15:14:41.95088Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f20dfa43a2e4607","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T15:14:41.950914Z","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-06-17T15:14:41.950926Z","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-06-17T15:14:41.9531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"info","ts":"2026-06-17T15:14:41.954051Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","added-peer-id":"5f20dfa43a2e4607","added-peer-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-06-17T15:14:41.956052Z","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-06-17T15:14:41.956327Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-06-17T15:14:41.956423Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-06-17T15:14:41.957188Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f20dfa43a2e4607","initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T15:14:41.957228Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T15:14:42.536385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T15:14:42.536536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T15:14:42.536614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgPreVoteResp from 5f20dfa43a2e4607 at term 1"} {"level":"info","ts":"2026-06-17T15:14:42.536681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became candidate at term 2"} {"level":"info","ts":"2026-06-17T15:14:42.536728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgVoteResp from 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-06-17T15:14:42.536769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became leader at term 2"} {"level":"info","ts":"2026-06-17T15:14:42.536804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f20dfa43a2e4607 elected leader 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-06-17T15:14:42.537938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:42.539094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:14:42.539273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:14:42.539534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T15:14:42.539592Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T15:14:42.539696Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f20dfa43a2e4607","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.236:2379]}","request-path":"/0/members/5f20dfa43a2e4607/attributes","cluster-id":"85a3671ea9bffea4","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T15:14:42.539787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:42.539972Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:42.540049Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:42.542506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.236:2379"} {"level":"info","ts":"2026-06-17T15:14:42.543301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-17T15:14:45.126546Z","caller":"traceutil/trace.go:171","msg":"trace[1835665153] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"128.979214ms","start":"2026-06-17T15:14:44.997541Z","end":"2026-06-17T15:14:45.12652Z","steps":["trace[1835665153] 'process raft request' (duration: 128.070826ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.447284Z","caller":"traceutil/trace.go:171","msg":"trace[73663567] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"100.525559ms","start":"2026-06-17T15:14:47.346723Z","end":"2026-06-17T15:14:47.447249Z","steps":["trace[73663567] 'process raft request' (duration: 100.267327ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.610166Z","caller":"traceutil/trace.go:171","msg":"trace[35139531] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"115.239121ms","start":"2026-06-17T15:14:47.4949Z","end":"2026-06-17T15:14:47.610139Z","steps":["trace[35139531] 'process raft request' (duration: 115.182541ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.610193Z","caller":"traceutil/trace.go:171","msg":"trace[1832500036] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"115.521675ms","start":"2026-06-17T15:14:47.494634Z","end":"2026-06-17T15:14:47.610156Z","steps":["trace[1832500036] 'process raft request' (duration: 115.165372ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.616925Z","caller":"traceutil/trace.go:171","msg":"trace[2051628936] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"110.23062ms","start":"2026-06-17T15:14:47.506667Z","end":"2026-06-17T15:14:47.616897Z","steps":["trace[2051628936] 'process raft request' (duration: 110.019309ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.616979Z","caller":"traceutil/trace.go:171","msg":"trace[296733106] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"102.731888ms","start":"2026-06-17T15:14:47.51421Z","end":"2026-06-17T15:14:47.616942Z","steps":["trace[296733106] 'process raft request' (duration: 102.592167ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:47.617274Z","caller":"traceutil/trace.go:171","msg":"trace[2113047878] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"102.073961ms","start":"2026-06-17T15:14:47.51519Z","end":"2026-06-17T15:14:47.617264Z","steps":["trace[2113047878] 'process raft request' (duration: 101.671848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:56.619439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5046176550091395677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T15:14:56.751181Z","caller":"traceutil/trace.go:171","msg":"trace[1300142074] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"632.536833ms","start":"2026-06-17T15:14:56.118615Z","end":"2026-06-17T15:14:56.751151Z","steps":["trace[1300142074] 'read index received' (duration: 632.293411ms)","trace[1300142074] 'applied index is now lower than readState.Index' (duration: 242.142µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:14:56.751633Z","caller":"traceutil/trace.go:171","msg":"trace[205768771] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"909.66655ms","start":"2026-06-17T15:14:55.841953Z","end":"2026-06-17T15:14:56.75162Z","steps":["trace[205768771] 'process raft request' (duration: 908.964744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:56.752118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.213167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:14:56.75225Z","caller":"traceutil/trace.go:171","msg":"trace[1813233978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"372.436269ms","start":"2026-06-17T15:14:56.379789Z","end":"2026-06-17T15:14:56.752225Z","steps":["trace[1813233978] 'agreement among raft nodes before linearized reading' (duration: 372.196947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:56.752272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:55.84193Z","time spent":"909.734711ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:14:56.752322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.379758Z","time spent":"372.545539ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T15:14:56.752676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.067415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-17T15:14:56.752707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.810481ms","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-06-17T15:14:56.752717Z","caller":"traceutil/trace.go:171","msg":"trace[881268846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"634.140086ms","start":"2026-06-17T15:14:56.118565Z","end":"2026-06-17T15:14:56.752705Z","steps":["trace[881268846] 'agreement among raft nodes before linearized reading' (duration: 634.059976ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:56.75275Z","caller":"traceutil/trace.go:171","msg":"trace[1169425566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"372.902692ms","start":"2026-06-17T15:14:56.379833Z","end":"2026-06-17T15:14:56.752736Z","steps":["trace[1169425566] 'agreement among raft nodes before linearized reading' (duration: 372.820201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:56.752789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.379798Z","time spent":"372.984803ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T15:14:56.7529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.374504ms","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-06-17T15:14:56.752924Z","caller":"traceutil/trace.go:171","msg":"trace[968769557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"238.453745ms","start":"2026-06-17T15:14:56.514463Z","end":"2026-06-17T15:14:56.752917Z","steps":["trace[968769557] 'agreement among raft nodes before linearized reading' (duration: 238.409755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:56.752754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.118538Z","time spent":"634.208877ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-17T15:14:57.345598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.880186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:14:57.34604Z","caller":"traceutil/trace.go:171","msg":"trace[1655759480] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"586.837629ms","start":"2026-06-17T15:14:56.759155Z","end":"2026-06-17T15:14:57.345992Z","steps":["trace[1655759480] 'process raft request' (duration: 180.267708ms)","trace[1655759480] 'compare' (duration: 405.734505ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:14:57.346173Z","caller":"traceutil/trace.go:171","msg":"trace[267191798] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"586.95366ms","start":"2026-06-17T15:14:56.759192Z","end":"2026-06-17T15:14:57.346145Z","steps":["trace[267191798] 'process raft request' (duration: 586.715298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:57.346269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.759141Z","time spent":"586.9973ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:14:57.34632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.759155Z","time spent":"587.077271ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:14:57.346455Z","caller":"traceutil/trace.go:171","msg":"trace[1333502801] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"587.129991ms","start":"2026-06-17T15:14:56.759303Z","end":"2026-06-17T15:14:57.346433Z","steps":["trace[1333502801] 'process raft request' (duration: 586.797629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:57.34657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.759292Z","time spent":"587.225572ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:14:57.346944Z","caller":"traceutil/trace.go:171","msg":"trace[1117523029] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"587.540535ms","start":"2026-06-17T15:14:56.759367Z","end":"2026-06-17T15:14:57.346907Z","steps":["trace[1117523029] 'process raft request' (duration: 586.990461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:57.347131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.759359Z","time spent":"587.684886ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:14:57.347677Z","caller":"traceutil/trace.go:171","msg":"trace[1646499245] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"579.254768ms","start":"2026-06-17T15:14:56.768391Z","end":"2026-06-17T15:14:57.347645Z","steps":["trace[1646499245] 'process raft request' (duration: 578.296091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:57.347822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:56.768355Z","time spent":"579.395589ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:14:57.88197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.295607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:14:57.882119Z","caller":"traceutil/trace.go:171","msg":"trace[178008194] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"247.393984ms","start":"2026-06-17T15:14:57.634694Z","end":"2026-06-17T15:14:57.882088Z","steps":["trace[178008194] 'process raft request' (duration: 83.860116ms)","trace[178008194] 'compare' (duration: 163.152715ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:05.709058Z","caller":"traceutil/trace.go:171","msg":"trace[1796283437] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"179.043213ms","start":"2026-06-17T15:15:05.529986Z","end":"2026-06-17T15:15:05.709029Z","steps":["trace[1796283437] 'process raft request' (duration: 178.865122ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:20.967068Z","caller":"traceutil/trace.go:171","msg":"trace[375871579] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"116.899841ms","start":"2026-06-17T15:15:20.849959Z","end":"2026-06-17T15:15:20.966859Z","steps":["trace[375871579] 'process raft request' (duration: 116.676212ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:21.947475Z","caller":"traceutil/trace.go:171","msg":"trace[198524939] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"133.309198ms","start":"2026-06-17T15:15:21.81415Z","end":"2026-06-17T15:15:21.947459Z","steps":["trace[198524939] 'process raft request' (duration: 127.976523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.73715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.246439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:22.737305Z","caller":"traceutil/trace.go:171","msg":"trace[1918776589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"322.483898ms","start":"2026-06-17T15:15:22.414789Z","end":"2026-06-17T15:15:22.737273Z","steps":["trace[1918776589] 'range keys from in-memory index tree' (duration: 322.181366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.737371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:22.414777Z","time spent":"322.583431ms","remote":"127.0.0.1:55752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:15:22.737327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.222595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-17T15:15:22.737598Z","caller":"traceutil/trace.go:171","msg":"trace[1074892939] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"343.538858ms","start":"2026-06-17T15:15:22.393961Z","end":"2026-06-17T15:15:22.7375Z","steps":["trace[1074892939] 'range keys from in-memory index tree' (duration: 342.927034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.737885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:22.39394Z","time spent":"343.925233ms","remote":"127.0.0.1:55792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7731,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-17T15:15:22.746258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.75449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-06-17T15:15:22.746314Z","caller":"traceutil/trace.go:171","msg":"trace[826654285] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:591; }","duration":"309.860176ms","start":"2026-06-17T15:15:22.436438Z","end":"2026-06-17T15:15:22.746298Z","steps":["trace[826654285] 'range keys from in-memory index tree' (duration: 300.375598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.746342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:22.436414Z","time spent":"309.921387ms","remote":"127.0.0.1:55792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570119,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-06-17T15:15:22.746787Z","caller":"traceutil/trace.go:171","msg":"trace[1515590035] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"259.81963ms","start":"2026-06-17T15:15:22.486758Z","end":"2026-06-17T15:15:22.746578Z","steps":["trace[1515590035] 'read index received' (duration: 249.30845ms)","trace[1515590035] 'applied index is now lower than readState.Index' (duration: 10.51009ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:22.746869Z","caller":"traceutil/trace.go:171","msg":"trace[459990517] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"300.027152ms","start":"2026-06-17T15:15:22.446791Z","end":"2026-06-17T15:15:22.746818Z","steps":["trace[459990517] 'process raft request' (duration: 298.335945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.746941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.168584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:22.746975Z","caller":"traceutil/trace.go:171","msg":"trace[129913139] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"260.283178ms","start":"2026-06-17T15:15:22.486681Z","end":"2026-06-17T15:15:22.746964Z","steps":["trace[129913139] 'agreement among raft nodes before linearized reading' (duration: 260.205445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:22.746999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:22.446767Z","time spent":"300.157837ms","remote":"127.0.0.1:55792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:15:27.122549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5046176550091396204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:15:27.253317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.657488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:27.253441Z","caller":"traceutil/trace.go:171","msg":"trace[903344795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"771.824355ms","start":"2026-06-17T15:15:26.48159Z","end":"2026-06-17T15:15:27.253414Z","steps":["trace[903344795] 'range keys from in-memory index tree' (duration: 771.592935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.253495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:26.481548Z","time spent":"771.937978ms","remote":"127.0.0.1:55756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-17T15:15:27.258884Z","caller":"traceutil/trace.go:171","msg":"trace[359495788] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"637.127793ms","start":"2026-06-17T15:15:26.621682Z","end":"2026-06-17T15:15:27.25881Z","steps":["trace[359495788] 'read index received' (duration: 633.947003ms)","trace[359495788] 'applied index is now lower than readState.Index' (duration: 3.1792ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:27.263142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.418554ms","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:5"} {"level":"warn","ts":"2026-06-17T15:15:27.263294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.446785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:27.264167Z","caller":"traceutil/trace.go:171","msg":"trace[315873330] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"642.424563ms","start":"2026-06-17T15:15:26.621724Z","end":"2026-06-17T15:15:27.264148Z","steps":["trace[315873330] 'agreement among raft nodes before linearized reading' (duration: 641.444095ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:27.264057Z","caller":"traceutil/trace.go:171","msg":"trace[1043019030] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"642.354861ms","start":"2026-06-17T15:15:26.621646Z","end":"2026-06-17T15:15:27.264001Z","steps":["trace[1043019030] 'agreement among raft nodes before linearized reading' (duration: 641.398794ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:27.26328Z","caller":"traceutil/trace.go:171","msg":"trace[919929014] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"644.030603ms","start":"2026-06-17T15:15:26.619137Z","end":"2026-06-17T15:15:27.263168Z","steps":["trace[919929014] 'process raft request' (duration: 636.897875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.264284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:26.621697Z","time spent":"642.532697ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-06-17T15:15:27.263342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.493547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:15:27.264485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:26.619064Z","time spent":"645.286912ms","remote":"127.0.0.1:55836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:15:27.2645Z","caller":"traceutil/trace.go:171","msg":"trace[2013754905] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:634; }","duration":"642.712843ms","start":"2026-06-17T15:15:26.621741Z","end":"2026-06-17T15:15:27.264454Z","steps":["trace[2013754905] 'agreement among raft nodes before linearized reading' (duration: 641.447635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.264684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:26.621687Z","time spent":"642.89064ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-06-17T15:15:27.265516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:26.621636Z","time spent":"642.634401ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:15:28.929452Z","caller":"traceutil/trace.go:171","msg":"trace[358333326] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"202.860642ms","start":"2026-06-17T15:15:28.726545Z","end":"2026-06-17T15:15:28.929406Z","steps":["trace[358333326] 'process raft request' (duration: 201.779132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:29.106481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.293986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:15:29.106585Z","caller":"traceutil/trace.go:171","msg":"trace[260162460] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"235.95878ms","start":"2026-06-17T15:15:28.870607Z","end":"2026-06-17T15:15:29.106566Z","steps":["trace[260162460] 'process raft request' (duration: 101.491508ms)","trace[260162460] 'compare' (duration: 134.154381ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:31.467768Z","caller":"traceutil/trace.go:171","msg":"trace[375137476] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"885.868788ms","start":"2026-06-17T15:15:30.581868Z","end":"2026-06-17T15:15:31.467736Z","steps":["trace[375137476] 'process raft request' (duration: 885.65843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:31.468025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.581842Z","time spent":"886.042154ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:15:57.744851Z","caller":"traceutil/trace.go:171","msg":"trace[1845129960] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"109.725181ms","start":"2026-06-17T15:15:57.635105Z","end":"2026-06-17T15:15:57.74483Z","steps":["trace[1845129960] 'process raft request' (duration: 109.453624ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:15.996963Z","caller":"traceutil/trace.go:171","msg":"trace[109060528] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"118.290424ms","start":"2026-06-17T15:16:15.878642Z","end":"2026-06-17T15:16:15.996933Z","steps":["trace[109060528] 'process raft request' (duration: 118.09873ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:16.946164Z","caller":"traceutil/trace.go:171","msg":"trace[1005214109] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"413.793528ms","start":"2026-06-17T15:16:16.532344Z","end":"2026-06-17T15:16:16.946138Z","steps":["trace[1005214109] 'process raft request' (duration: 413.645925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:16:16.94634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:16:16.532313Z","time spent":"413.935081ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:16:20.114385Z","caller":"traceutil/trace.go:171","msg":"trace[187746075] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"103.07956ms","start":"2026-06-17T15:16:20.011276Z","end":"2026-06-17T15:16:20.114356Z","steps":["trace[187746075] 'read index received' (duration: 56.372768ms)","trace[187746075] 'applied index is now lower than readState.Index' (duration: 46.705282ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:16:20.114422Z","caller":"traceutil/trace.go:171","msg":"trace[292546832] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1205; }","duration":"122.038065ms","start":"2026-06-17T15:16:19.992352Z","end":"2026-06-17T15:16:20.11439Z","steps":["trace[292546832] 'process raft request' (duration: 75.347374ms)","trace[292546832] 'compare' (duration: 46.498377ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:16:20.114674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.362036ms","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-06-17T15:16:20.114729Z","caller":"traceutil/trace.go:171","msg":"trace[119026786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"103.461087ms","start":"2026-06-17T15:16:20.011252Z","end":"2026-06-17T15:16:20.114713Z","steps":["trace[119026786] 'agreement among raft nodes before linearized reading' (duration: 103.241973ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:35.129523Z","caller":"traceutil/trace.go:171","msg":"trace[1297091931] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"200.05985ms","start":"2026-06-17T15:16:34.929418Z","end":"2026-06-17T15:16:35.129478Z","steps":["trace[1297091931] 'process raft request' (duration: 199.834835ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:42.579185Z","caller":"traceutil/trace.go:171","msg":"trace[1375121033] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"260.533646ms","start":"2026-06-17T15:16:42.318525Z","end":"2026-06-17T15:16:42.579059Z","steps":["trace[1375121033] 'process raft request' (duration: 260.243449ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:42.579582Z","caller":"traceutil/trace.go:171","msg":"trace[2101124693] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"260.840732ms","start":"2026-06-17T15:16:42.318679Z","end":"2026-06-17T15:16:42.57952Z","steps":["trace[2101124693] 'process raft request' (duration: 260.311453ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:42.580727Z","caller":"traceutil/trace.go:171","msg":"trace[1584684117] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1500; }","duration":"262.102355ms","start":"2026-06-17T15:16:42.318605Z","end":"2026-06-17T15:16:42.580707Z","steps":["trace[1584684117] 'read index received' (duration: 92.903403ms)","trace[1584684117] 'applied index is now lower than readState.Index' (duration: 169.197412ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:16:42.580992Z","caller":"traceutil/trace.go:171","msg":"trace[1066246017] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"261.078646ms","start":"2026-06-17T15:16:42.319893Z","end":"2026-06-17T15:16:42.580971Z","steps":["trace[1066246017] 'process raft request' (duration: 259.524527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:16:42.58108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.438931ms","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-06-17T15:16:42.58114Z","caller":"traceutil/trace.go:171","msg":"trace[1631585737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1454; }","duration":"262.536473ms","start":"2026-06-17T15:16:42.318583Z","end":"2026-06-17T15:16:42.58112Z","steps":["trace[1631585737] 'agreement among raft nodes before linearized reading' (duration: 262.198507ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:01.584015Z","caller":"traceutil/trace.go:171","msg":"trace[382536319] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"154.174608ms","start":"2026-06-17T15:17:01.4298Z","end":"2026-06-17T15:17:01.583975Z","steps":["trace[382536319] 'process raft request' (duration: 153.956994ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:13.491726Z","caller":"traceutil/trace.go:171","msg":"trace[1785474714] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"225.949857ms","start":"2026-06-17T15:17:13.265746Z","end":"2026-06-17T15:17:13.491696Z","steps":["trace[1785474714] 'process raft request' (duration: 225.805295ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:13.558351Z","caller":"traceutil/trace.go:171","msg":"trace[1921295583] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"197.305208ms","start":"2026-06-17T15:17:13.361018Z","end":"2026-06-17T15:17:13.558323Z","steps":["trace[1921295583] 'read index received' (duration: 130.474242ms)","trace[1921295583] 'applied index is now lower than readState.Index' (duration: 66.829876ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:13.558448Z","caller":"traceutil/trace.go:171","msg":"trace[1507464097] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"199.623045ms","start":"2026-06-17T15:17:13.358765Z","end":"2026-06-17T15:17:13.558388Z","steps":["trace[1507464097] 'process raft request' (duration: 199.431062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:13.558669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.613444ms","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-06-17T15:17:13.558746Z","caller":"traceutil/trace.go:171","msg":"trace[2085290464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"197.747725ms","start":"2026-06-17T15:17:13.360975Z","end":"2026-06-17T15:17:13.558723Z","steps":["trace[2085290464] 'agreement among raft nodes before linearized reading' (duration: 197.511832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:13.558833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.419615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:17:13.558941Z","caller":"traceutil/trace.go:171","msg":"trace[1181370575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"150.638257ms","start":"2026-06-17T15:17:13.408278Z","end":"2026-06-17T15:17:13.558916Z","steps":["trace[1181370575] 'agreement among raft nodes before linearized reading' (duration: 150.405444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:14.431813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.104632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:14.432116Z","caller":"traceutil/trace.go:171","msg":"trace[1839125774] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1972; }","duration":"355.774802ms","start":"2026-06-17T15:17:14.076318Z","end":"2026-06-17T15:17:14.432093Z","steps":["trace[1839125774] 'read index received' (duration: 129.267004ms)","trace[1839125774] 'applied index is now lower than readState.Index' (duration: 226.506508ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:14.432247Z","caller":"traceutil/trace.go:171","msg":"trace[1106130897] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"601.34022ms","start":"2026-06-17T15:17:13.83088Z","end":"2026-06-17T15:17:14.43222Z","steps":["trace[1106130897] 'process raft request' (duration: 601.101896ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:14.432331Z","caller":"traceutil/trace.go:171","msg":"trace[1463860923] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"657.387328ms","start":"2026-06-17T15:17:13.774889Z","end":"2026-06-17T15:17:14.432276Z","steps":["trace[1463860923] 'process raft request' (duration: 430.656846ms)","trace[1463860923] 'compare' (duration: 225.797868ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:14.432379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:13.830845Z","time spent":"601.481203ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:14.432533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:13.774856Z","time spent":"657.56953ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:14.432729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.397861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-17T15:17:14.432812Z","caller":"traceutil/trace.go:171","msg":"trace[1717862033] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72; range_end:; response_count:1; response_revision:1895; }","duration":"356.541544ms","start":"2026-06-17T15:17:14.076249Z","end":"2026-06-17T15:17:14.43279Z","steps":["trace[1717862033] 'agreement among raft nodes before linearized reading' (duration: 355.969435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:14.432856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.076221Z","time spent":"356.626695ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" "} {"level":"info","ts":"2026-06-17T15:17:14.62994Z","caller":"traceutil/trace.go:171","msg":"trace[904971968] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"197.756301ms","start":"2026-06-17T15:17:14.432148Z","end":"2026-06-17T15:17:14.629904Z","steps":["trace[904971968] 'read index received' (duration: 186.485135ms)","trace[904971968] 'applied index is now lower than readState.Index' (duration: 11.269676ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:14.630037Z","caller":"traceutil/trace.go:171","msg":"trace[512383288] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"370.770646ms","start":"2026-06-17T15:17:14.259202Z","end":"2026-06-17T15:17:14.629972Z","steps":["trace[512383288] 'process raft request' (duration: 359.45281ms)","trace[512383288] 'compare' (duration: 11.084912ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:14.630189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.25917Z","time spent":"370.935979ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:14.630309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.15335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:17:14.630361Z","caller":"traceutil/trace.go:171","msg":"trace[1725057637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"220.303992ms","start":"2026-06-17T15:17:14.410044Z","end":"2026-06-17T15:17:14.630348Z","steps":["trace[1725057637] 'agreement among raft nodes before linearized reading' (duration: 220.109879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:14.630583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.070087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:17:14.630699Z","caller":"traceutil/trace.go:171","msg":"trace[1917817744] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1896; }","duration":"435.258169ms","start":"2026-06-17T15:17:14.195413Z","end":"2026-06-17T15:17:14.630671Z","steps":["trace[1917817744] 'agreement among raft nodes before linearized reading' (duration: 434.995885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:14.630824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.195394Z","time spent":"435.354791ms","remote":"127.0.0.1:56124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-17T15:17:14.631029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.030006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-17T15:17:14.631155Z","caller":"traceutil/trace.go:171","msg":"trace[1379250379] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72; range_end:; response_count:1; response_revision:1896; }","duration":"485.454141ms","start":"2026-06-17T15:17:14.145677Z","end":"2026-06-17T15:17:14.631131Z","steps":["trace[1379250379] 'agreement among raft nodes before linearized reading' (duration: 484.562678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:14.631204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.145657Z","time spent":"485.534052ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" "} {"level":"warn","ts":"2026-06-17T15:17:15.32031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.061085ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:17:15.320919Z","caller":"traceutil/trace.go:171","msg":"trace[985057572] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1897; }","duration":"177.681274ms","start":"2026-06-17T15:17:15.14321Z","end":"2026-06-17T15:17:15.320891Z","steps":["trace[985057572] 'range keys from in-memory index tree' (duration: 177.040903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.321703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.085917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:15.321845Z","caller":"traceutil/trace.go:171","msg":"trace[114177810] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"585.14186ms","start":"2026-06-17T15:17:14.73668Z","end":"2026-06-17T15:17:15.321822Z","steps":["trace[114177810] 'read index received' (duration: 256.452674ms)","trace[114177810] 'applied index is now lower than readState.Index' (duration: 328.687186ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:15.32203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.357104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:17:15.322067Z","caller":"traceutil/trace.go:171","msg":"trace[1270492311] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1898; }","duration":"585.455145ms","start":"2026-06-17T15:17:14.7366Z","end":"2026-06-17T15:17:15.322056Z","steps":["trace[1270492311] 'agreement among raft nodes before linearized reading' (duration: 585.344493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.322109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.736574Z","time spent":"585.525766ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":23,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-17T15:17:15.322353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.963239ms","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-06-17T15:17:15.322438Z","caller":"traceutil/trace.go:171","msg":"trace[534123174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"535.113031ms","start":"2026-06-17T15:17:14.787312Z","end":"2026-06-17T15:17:15.322425Z","steps":["trace[534123174] 'agreement among raft nodes before linearized reading' (duration: 534.727375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.322451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.099176ms","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-06-17T15:17:15.322528Z","caller":"traceutil/trace.go:171","msg":"trace[1053302840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1898; }","duration":"527.253728ms","start":"2026-06-17T15:17:14.795255Z","end":"2026-06-17T15:17:15.322509Z","steps":["trace[1053302840] 'agreement among raft nodes before linearized reading' (duration: 526.911103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.322605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.795223Z","time spent":"527.36943ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T15:17:15.322728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.038197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:17:15.322775Z","caller":"traceutil/trace.go:171","msg":"trace[1435241139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"529.108297ms","start":"2026-06-17T15:17:14.793657Z","end":"2026-06-17T15:17:15.322765Z","steps":["trace[1435241139] 'agreement among raft nodes before linearized reading' (duration: 528.921964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.322805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.793646Z","time spent":"529.152998ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T15:17:15.322871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.537942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-17T15:17:15.323639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.146753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:17:15.323825Z","caller":"traceutil/trace.go:171","msg":"trace[1653950939] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"683.572253ms","start":"2026-06-17T15:17:14.638251Z","end":"2026-06-17T15:17:15.321823Z","steps":["trace[1653950939] 'process raft request' (duration: 354.999689ms)","trace[1653950939] 'compare' (duration: 327.526207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:15.323994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.638231Z","time spent":"685.659115ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:15.323861Z","caller":"traceutil/trace.go:171","msg":"trace[571180554] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1898; }","duration":"113.491119ms","start":"2026-06-17T15:17:15.210352Z","end":"2026-06-17T15:17:15.323843Z","steps":["trace[571180554] 'agreement among raft nodes before linearized reading' (duration: 111.965756ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:15.32377Z","caller":"traceutil/trace.go:171","msg":"trace[1714647021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1898; }","duration":"528.361235ms","start":"2026-06-17T15:17:14.795249Z","end":"2026-06-17T15:17:15.32361Z","steps":["trace[1714647021] 'agreement among raft nodes before linearized reading' (duration: 527.084736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:15.32514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.795225Z","time spent":"529.845408ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T15:17:15.323386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:14.787284Z","time spent":"535.221572ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-17T15:17:22.759723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.454384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:22.759928Z","caller":"traceutil/trace.go:171","msg":"trace[1683305250] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"105.45697ms","start":"2026-06-17T15:17:22.654454Z","end":"2026-06-17T15:17:22.759911Z","steps":["trace[1683305250] 'process raft request' (duration: 105.378299ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:22.760207Z","caller":"traceutil/trace.go:171","msg":"trace[1537457546] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"303.368795ms","start":"2026-06-17T15:17:22.456805Z","end":"2026-06-17T15:17:22.760173Z","steps":["trace[1537457546] 'process raft request' (duration: 146.371903ms)","trace[1537457546] 'compare' (duration: 156.222041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:22.760791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:22.456784Z","time spent":"303.947063ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:22.760399Z","caller":"traceutil/trace.go:171","msg":"trace[1442078895] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"173.54383ms","start":"2026-06-17T15:17:22.586813Z","end":"2026-06-17T15:17:22.760357Z","steps":["trace[1442078895] 'read index received' (duration: 16.245093ms)","trace[1442078895] 'applied index is now lower than readState.Index' (duration: 157.290397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:22.760612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.781753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-trcdm\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-06-17T15:17:22.761201Z","caller":"traceutil/trace.go:171","msg":"trace[375539901] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-trcdm; range_end:; response_count:1; response_revision:1980; }","duration":"174.395813ms","start":"2026-06-17T15:17:22.586765Z","end":"2026-06-17T15:17:22.761161Z","steps":["trace[375539901] 'agreement among raft nodes before linearized reading' (duration: 173.675712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.935294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5046176550091398952,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T15:17:29.944722Z","caller":"traceutil/trace.go:171","msg":"trace[403578940] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"512.083554ms","start":"2026-06-17T15:17:29.432614Z","end":"2026-06-17T15:17:29.944698Z","steps":["trace[403578940] 'process raft request' (duration: 511.895072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.944861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:29.432594Z","time spent":"512.190027ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:29.9453Z","caller":"traceutil/trace.go:171","msg":"trace[55305305] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2109; }","duration":"510.530291ms","start":"2026-06-17T15:17:29.43474Z","end":"2026-06-17T15:17:29.945271Z","steps":["trace[55305305] 'read index received' (duration: 509.765661ms)","trace[55305305] 'applied index is now lower than readState.Index' (duration: 763.89µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:29.945426Z","caller":"traceutil/trace.go:171","msg":"trace[16301570] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"328.065473ms","start":"2026-06-17T15:17:29.61735Z","end":"2026-06-17T15:17:29.945416Z","steps":["trace[16301570] 'process raft request' (duration: 327.812739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.945586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:29.617318Z","time spent":"328.131004ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:29.945705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.143453ms","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-06-17T15:17:29.945766Z","caller":"traceutil/trace.go:171","msg":"trace[163065567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2029; }","duration":"505.216585ms","start":"2026-06-17T15:17:29.440531Z","end":"2026-06-17T15:17:29.945747Z","steps":["trace[163065567] 'agreement among raft nodes before linearized reading' (duration: 504.995312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.945811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:29.440524Z","time spent":"505.276516ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-17T15:17:29.946032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.281402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-17T15:17:29.946048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.186343ms","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-06-17T15:17:29.946076Z","caller":"traceutil/trace.go:171","msg":"trace[404790892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2029; }","duration":"511.361844ms","start":"2026-06-17T15:17:29.434704Z","end":"2026-06-17T15:17:29.946065Z","steps":["trace[404790892] 'agreement among raft nodes before linearized reading' (duration: 511.003689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.946113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:29.434693Z","time spent":"511.409554ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T15:17:29.94612Z","caller":"traceutil/trace.go:171","msg":"trace[1378906204] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2029; }","duration":"113.286174ms","start":"2026-06-17T15:17:29.83281Z","end":"2026-06-17T15:17:29.946096Z","steps":["trace[1378906204] 'agreement among raft nodes before linearized reading' (duration: 112.873158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.94618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.004854ms","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-06-17T15:17:29.946225Z","caller":"traceutil/trace.go:171","msg":"trace[1088022341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2029; }","duration":"501.071395ms","start":"2026-06-17T15:17:29.445141Z","end":"2026-06-17T15:17:29.946212Z","steps":["trace[1088022341] 'agreement among raft nodes before linearized reading' (duration: 500.952623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:29.946269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:29.445118Z","time spent":"501.140356ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-17T15:17:42.871778Z","caller":"traceutil/trace.go:171","msg":"trace[281855412] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"514.991379ms","start":"2026-06-17T15:17:42.356738Z","end":"2026-06-17T15:17:42.87173Z","steps":["trace[281855412] 'process raft request' (duration: 514.823077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:42.87195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.356701Z","time spent":"515.158791ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11648,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:43.394169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.494383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:43.397951Z","caller":"traceutil/trace.go:171","msg":"trace[377679996] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"524.168016ms","start":"2026-06-17T15:17:42.873758Z","end":"2026-06-17T15:17:43.397926Z","steps":["trace[377679996] 'process raft request' (duration: 524.121146ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:43.398Z","caller":"traceutil/trace.go:171","msg":"trace[1367445244] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"702.773758ms","start":"2026-06-17T15:17:42.695201Z","end":"2026-06-17T15:17:43.397974Z","steps":["trace[1367445244] 'process raft request' (duration: 702.472374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.398111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.873732Z","time spent":"524.305528ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:43.398122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.69518Z","time spent":"702.881069ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:43.398219Z","caller":"traceutil/trace.go:171","msg":"trace[1443408771] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"1.005598442s","start":"2026-06-17T15:17:42.392609Z","end":"2026-06-17T15:17:43.398208Z","steps":["trace[1443408771] 'process raft request' (duration: 685.966922ms)","trace[1443408771] 'compare' (duration: 315.25421ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:43.398258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.392587Z","time spent":"1.005655273s","remote":"127.0.0.1:55816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:17:43.39829Z","caller":"traceutil/trace.go:171","msg":"trace[987929988] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2186; }","duration":"988.396175ms","start":"2026-06-17T15:17:42.409886Z","end":"2026-06-17T15:17:43.398282Z","steps":["trace[987929988] 'read index received' (duration: 461.564182ms)","trace[987929988] 'applied index is now lower than readState.Index' (duration: 526.830933ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:43.398357Z","caller":"traceutil/trace.go:171","msg":"trace[520594782] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"526.272805ms","start":"2026-06-17T15:17:42.872079Z","end":"2026-06-17T15:17:43.398351Z","steps":["trace[520594782] 'process raft request' (duration: 525.757108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.398406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.872044Z","time spent":"526.332316ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:43.398614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.718319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:17:43.398647Z","caller":"traceutil/trace.go:171","msg":"trace[1439811286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"988.805321ms","start":"2026-06-17T15:17:42.409835Z","end":"2026-06-17T15:17:43.39864Z","steps":["trace[1439811286] 'agreement among raft nodes before linearized reading' (duration: 988.73371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.398667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.409811Z","time spent":"988.852961ms","remote":"127.0.0.1:55752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:17:43.398809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.62599ms","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-06-17T15:17:43.398666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.899161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:17:43.398845Z","caller":"traceutil/trace.go:171","msg":"trace[669085985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"354.714411ms","start":"2026-06-17T15:17:43.044123Z","end":"2026-06-17T15:17:43.398837Z","steps":["trace[669085985] 'agreement among raft nodes before linearized reading' (duration: 354.60404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.398873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:43.044098Z","time spent":"354.770102ms","remote":"127.0.0.1:34790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-17T15:17:43.399026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.181127ms","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-06-17T15:17:43.399052Z","caller":"traceutil/trace.go:171","msg":"trace[644964207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"490.207027ms","start":"2026-06-17T15:17:42.908839Z","end":"2026-06-17T15:17:43.399046Z","steps":["trace[644964207] 'agreement among raft nodes before linearized reading' (duration: 490.140566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.399069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.90877Z","time spent":"490.295758ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-17T15:17:43.398849Z","caller":"traceutil/trace.go:171","msg":"trace[379089060] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2106; }","duration":"730.126954ms","start":"2026-06-17T15:17:42.668715Z","end":"2026-06-17T15:17:43.398842Z","steps":["trace[379089060] 'agreement among raft nodes before linearized reading' (duration: 729.911212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:43.399172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:42.668694Z","time spent":"730.46969ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:17:43.600486Z","caller":"traceutil/trace.go:171","msg":"trace[1821704019] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"184.795188ms","start":"2026-06-17T15:17:43.415651Z","end":"2026-06-17T15:17:43.600446Z","steps":["trace[1821704019] 'read index received' (duration: 127.044366ms)","trace[1821704019] 'applied index is now lower than readState.Index' (duration: 57.748992ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:43.601139Z","caller":"traceutil/trace.go:171","msg":"trace[1132147152] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"197.752637ms","start":"2026-06-17T15:17:43.403327Z","end":"2026-06-17T15:17:43.60108Z","steps":["trace[1132147152] 'process raft request' (duration: 139.507747ms)","trace[1132147152] 'compare' (duration: 57.361367ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:43.60133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.56895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9e750eeb41593\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-17T15:17:43.601412Z","caller":"traceutil/trace.go:171","msg":"trace[35810538] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b9e750eeb41593; range_end:; response_count:1; response_revision:2107; }","duration":"185.801722ms","start":"2026-06-17T15:17:43.415593Z","end":"2026-06-17T15:17:43.601395Z","steps":["trace[35810538] 'agreement among raft nodes before linearized reading' (duration: 185.316556ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:44.48298Z","caller":"traceutil/trace.go:171","msg":"trace[427690199] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"445.454496ms","start":"2026-06-17T15:17:44.037489Z","end":"2026-06-17T15:17:44.482943Z","steps":["trace[427690199] 'process raft request' (duration: 445.401815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:44.483175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:44.037479Z","time spent":"445.591208ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:44.483394Z","caller":"traceutil/trace.go:171","msg":"trace[2138337807] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"449.608502ms","start":"2026-06-17T15:17:44.033742Z","end":"2026-06-17T15:17:44.48335Z","steps":["trace[2138337807] 'process raft request' (duration: 448.963773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:44.483671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:44.033721Z","time spent":"449.848675ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:44.500557Z","caller":"traceutil/trace.go:171","msg":"trace[516095944] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"426.330044ms","start":"2026-06-17T15:17:44.074207Z","end":"2026-06-17T15:17:44.500537Z","steps":["trace[516095944] 'process raft request' (duration: 425.879788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:44.500653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:44.074185Z","time spent":"426.420865ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:44.638609Z","caller":"traceutil/trace.go:171","msg":"trace[859090075] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"128.166813ms","start":"2026-06-17T15:17:44.510376Z","end":"2026-06-17T15:17:44.638543Z","steps":["trace[859090075] 'process raft request' (duration: 55.019273ms)","trace[859090075] 'compare' (duration: 72.844476ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:45.589066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.084149ms","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-06-17T15:17:45.589164Z","caller":"traceutil/trace.go:171","msg":"trace[1790314770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2117; }","duration":"214.240761ms","start":"2026-06-17T15:17:45.374894Z","end":"2026-06-17T15:17:45.589135Z","steps":["trace[1790314770] 'range keys from in-memory index tree' (duration: 213.806925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:45.589236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.808665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-17T15:17:45.58942Z","caller":"traceutil/trace.go:171","msg":"trace[885539587] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2117; }","duration":"203.035948ms","start":"2026-06-17T15:17:45.386338Z","end":"2026-06-17T15:17:45.589374Z","steps":["trace[885539587] 'range keys from in-memory index tree' (duration: 202.45048ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:45.589397Z","caller":"traceutil/trace.go:171","msg":"trace[535005027] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"183.846576ms","start":"2026-06-17T15:17:45.405512Z","end":"2026-06-17T15:17:45.589358Z","steps":["trace[535005027] 'process raft request' (duration: 106.008925ms)","trace[535005027] 'compare' (duration: 77.235713ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:45.598919Z","caller":"traceutil/trace.go:171","msg":"trace[1490799196] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"189.21976ms","start":"2026-06-17T15:17:45.409651Z","end":"2026-06-17T15:17:45.59887Z","steps":["trace[1490799196] 'process raft request' (duration: 189.071948ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:45.599463Z","caller":"traceutil/trace.go:171","msg":"trace[384444789] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"193.59713ms","start":"2026-06-17T15:17:45.405846Z","end":"2026-06-17T15:17:45.599443Z","steps":["trace[384444789] 'process raft request' (duration: 192.723058ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:45.801781Z","caller":"traceutil/trace.go:171","msg":"trace[926189235] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"175.169729ms","start":"2026-06-17T15:17:45.626571Z","end":"2026-06-17T15:17:45.801741Z","steps":["trace[926189235] 'process raft request' (duration: 174.759134ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:45.811529Z","caller":"traceutil/trace.go:171","msg":"trace[978883656] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"177.073635ms","start":"2026-06-17T15:17:45.634374Z","end":"2026-06-17T15:17:45.811447Z","steps":["trace[978883656] 'process raft request' (duration: 176.71167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:46.246746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:45.927334Z","time spent":"319.404773ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-17T15:17:46.247526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.557313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:46.247907Z","caller":"traceutil/trace.go:171","msg":"trace[1622139049] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"383.278933ms","start":"2026-06-17T15:17:45.864542Z","end":"2026-06-17T15:17:46.247821Z","steps":["trace[1622139049] 'process raft request' (duration: 36.285714ms)","trace[1622139049] 'compare' (duration: 345.646031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:46.248435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.879944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-17T15:17:46.248489Z","caller":"traceutil/trace.go:171","msg":"trace[966090719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2124; }","duration":"176.988916ms","start":"2026-06-17T15:17:46.071488Z","end":"2026-06-17T15:17:46.248477Z","steps":["trace[966090719] 'agreement among raft nodes before linearized reading' (duration: 176.525909ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:46.24797Z","caller":"traceutil/trace.go:171","msg":"trace[1542760413] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2208; }","duration":"176.404067ms","start":"2026-06-17T15:17:46.071541Z","end":"2026-06-17T15:17:46.247945Z","steps":["trace[1542760413] 'read index received' (duration: 175.833189ms)","trace[1542760413] 'applied index is now lower than readState.Index' (duration: 569.448µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:46.248591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:45.864513Z","time spent":"383.844771ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:46.24823Z","caller":"traceutil/trace.go:171","msg":"trace[193669334] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"213.252808ms","start":"2026-06-17T15:17:46.034925Z","end":"2026-06-17T15:17:46.248178Z","steps":["trace[193669334] 'process raft request' (duration: 212.766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.244008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.345116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:17:47.244228Z","caller":"traceutil/trace.go:171","msg":"trace[5979718] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"597.830572ms","start":"2026-06-17T15:17:46.646376Z","end":"2026-06-17T15:17:47.244206Z","steps":["trace[5979718] 'process raft request' (duration: 597.768851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.244352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.646352Z","time spent":"597.974463ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:47.244402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.990707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-17T15:17:47.244464Z","caller":"traceutil/trace.go:171","msg":"trace[1323318309] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2126; }","duration":"989.084908ms","start":"2026-06-17T15:17:46.255363Z","end":"2026-06-17T15:17:47.244448Z","steps":["trace[1323318309] 'agreement among raft nodes before linearized reading' (duration: 988.917676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.244505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.255349Z","time spent":"989.147769ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-06-17T15:17:47.244707Z","caller":"traceutil/trace.go:171","msg":"trace[2066186746] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"989.529524ms","start":"2026-06-17T15:17:46.255163Z","end":"2026-06-17T15:17:47.244692Z","steps":["trace[2066186746] 'process raft request' (duration: 254.366207ms)","trace[2066186746] 'compare' (duration: 734.026502ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:47.244226Z","caller":"traceutil/trace.go:171","msg":"trace[251869320] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"988.812875ms","start":"2026-06-17T15:17:46.255386Z","end":"2026-06-17T15:17:47.244199Z","steps":["trace[251869320] 'read index received' (duration: 254.047072ms)","trace[251869320] 'applied index is now lower than readState.Index' (duration: 734.763983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:17:47.244821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.255142Z","time spent":"989.602315ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:47.246018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.137723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12404"} {"level":"info","ts":"2026-06-17T15:17:47.246129Z","caller":"traceutil/trace.go:171","msg":"trace[361819442] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2126; }","duration":"181.303465ms","start":"2026-06-17T15:17:47.064811Z","end":"2026-06-17T15:17:47.246115Z","steps":["trace[361819442] 'agreement among raft nodes before linearized reading' (duration: 181.026901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.381622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9e750eeb41593\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-17T15:17:47.246217Z","caller":"traceutil/trace.go:171","msg":"trace[743370958] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b9e750eeb41593; range_end:; response_count:1; response_revision:2126; }","duration":"599.454974ms","start":"2026-06-17T15:17:46.646746Z","end":"2026-06-17T15:17:47.246201Z","steps":["trace[743370958] 'agreement among raft nodes before linearized reading' (duration: 599.342563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.646734Z","time spent":"599.518745ms","remote":"127.0.0.1:55816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9e750eeb41593\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.169768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:17:47.246255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.014566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12404"} {"level":"info","ts":"2026-06-17T15:17:47.246313Z","caller":"traceutil/trace.go:171","msg":"trace[814834637] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2126; }","duration":"866.120506ms","start":"2026-06-17T15:17:46.380182Z","end":"2026-06-17T15:17:47.246302Z","steps":["trace[814834637] 'agreement among raft nodes before linearized reading' (duration: 864.661086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.380157Z","time spent":"866.196558ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12427,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.982715ms","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-06-17T15:17:47.246616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.43037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-17T15:17:47.246661Z","caller":"traceutil/trace.go:171","msg":"trace[31959706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2126; }","duration":"757.471191ms","start":"2026-06-17T15:17:46.489182Z","end":"2026-06-17T15:17:47.246653Z","steps":["trace[31959706] 'agreement among raft nodes before linearized reading' (duration: 757.40176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.489144Z","time spent":"757.532801ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.221832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-17T15:17:47.246619Z","caller":"traceutil/trace.go:171","msg":"trace[756163196] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"640.206808ms","start":"2026-06-17T15:17:46.606384Z","end":"2026-06-17T15:17:47.246591Z","steps":["trace[756163196] 'agreement among raft nodes before linearized reading' (duration: 639.824543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.095609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-06-17T15:17:47.246747Z","caller":"traceutil/trace.go:171","msg":"trace[959302750] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2126; }","duration":"828.296674ms","start":"2026-06-17T15:17:46.418437Z","end":"2026-06-17T15:17:47.246734Z","steps":["trace[959302750] 'agreement among raft nodes before linearized reading' (duration: 828.177322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.422957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:17:47.24684Z","caller":"traceutil/trace.go:171","msg":"trace[866722827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2126; }","duration":"837.6441ms","start":"2026-06-17T15:17:46.409188Z","end":"2026-06-17T15:17:47.246832Z","steps":["trace[866722827] 'agreement among raft nodes before linearized reading' (duration: 837.404247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.409098Z","time spent":"837.764952ms","remote":"127.0.0.1:55752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-17T15:17:47.246309Z","caller":"traceutil/trace.go:171","msg":"trace[20868299] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2126; }","duration":"434.464132ms","start":"2026-06-17T15:17:46.811834Z","end":"2026-06-17T15:17:47.246298Z","steps":["trace[20868299] 'agreement among raft nodes before linearized reading' (duration: 434.154058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.246939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.606362Z","time spent":"640.559662ms","remote":"127.0.0.1:34790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.951408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-17T15:17:47.246569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.439409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-17T15:17:47.247059Z","caller":"traceutil/trace.go:171","msg":"trace[731699243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"758.1341ms","start":"2026-06-17T15:17:46.488899Z","end":"2026-06-17T15:17:47.247033Z","steps":["trace[731699243] 'agreement among raft nodes before linearized reading' (duration: 757.744885ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:47.247094Z","caller":"traceutil/trace.go:171","msg":"trace[643507358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2126; }","duration":"740.935846ms","start":"2026-06-17T15:17:46.506109Z","end":"2026-06-17T15:17:47.247045Z","steps":["trace[643507358] 'agreement among raft nodes before linearized reading' (duration: 740.406459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.24712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.488876Z","time spent":"758.233942ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-17T15:17:47.246756Z","caller":"traceutil/trace.go:171","msg":"trace[727684980] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2126; }","duration":"836.147139ms","start":"2026-06-17T15:17:46.410599Z","end":"2026-06-17T15:17:47.246746Z","steps":["trace[727684980] 'agreement among raft nodes before linearized reading' (duration: 836.049938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.247159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.506101Z","time spent":"741.043987ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.373291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:17:47.24746Z","caller":"traceutil/trace.go:171","msg":"trace[1753573075] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2126; }","duration":"262.79802ms","start":"2026-06-17T15:17:46.984636Z","end":"2026-06-17T15:17:47.247434Z","steps":["trace[1753573075] 'agreement among raft nodes before linearized reading' (duration: 261.371591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.247201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.410591Z","time spent":"836.588566ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-06-17T15:17:47.246789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.418421Z","time spent":"828.360025ms","remote":"127.0.0.1:55920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-17T15:17:47.250679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:46.811823Z","time spent":"435.183612ms","remote":"127.0.0.1:56150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:17:47.560726Z","caller":"traceutil/trace.go:171","msg":"trace[1613516265] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"308.611428ms","start":"2026-06-17T15:17:47.252094Z","end":"2026-06-17T15:17:47.560705Z","steps":["trace[1613516265] 'read index received' (duration: 303.303216ms)","trace[1613516265] 'applied index is now lower than readState.Index' (duration: 5.307492ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:17:47.560873Z","caller":"traceutil/trace.go:171","msg":"trace[822247652] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"310.345752ms","start":"2026-06-17T15:17:47.250506Z","end":"2026-06-17T15:17:47.560852Z","steps":["trace[822247652] 'process raft request' (duration: 304.714866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.561018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.250488Z","time spent":"310.447584ms","remote":"127.0.0.1:55816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:47.561069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.933343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-06-17T15:17:47.561111Z","caller":"traceutil/trace.go:171","msg":"trace[608240343] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b7b2735c-be85-4120-b4f1-da007e8eba72; range_end:; response_count:1; response_revision:2127; }","duration":"309.038584ms","start":"2026-06-17T15:17:47.25206Z","end":"2026-06-17T15:17:47.561099Z","steps":["trace[608240343] 'agreement among raft nodes before linearized reading' (duration: 308.863982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.561143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.252034Z","time spent":"309.101995ms","remote":"127.0.0.1:55904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1326,"request content":"key:\"/registry/persistentvolumes/pvc-b7b2735c-be85-4120-b4f1-da007e8eba72\" "} {"level":"info","ts":"2026-06-17T15:17:47.643486Z","caller":"traceutil/trace.go:171","msg":"trace[1707497321] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"390.456307ms","start":"2026-06-17T15:17:47.252998Z","end":"2026-06-17T15:17:47.643454Z","steps":["trace[1707497321] 'process raft request' (duration: 390.175403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.643651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.252974Z","time spent":"390.591269ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:47.64375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.484797ms","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-06-17T15:17:47.64383Z","caller":"traceutil/trace.go:171","msg":"trace[761126171] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"390.354626ms","start":"2026-06-17T15:17:47.253466Z","end":"2026-06-17T15:17:47.643821Z","steps":["trace[761126171] 'process raft request' (duration: 389.89136ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:17:47.64386Z","caller":"traceutil/trace.go:171","msg":"trace[132772300] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2131; }","duration":"384.642879ms","start":"2026-06-17T15:17:47.259179Z","end":"2026-06-17T15:17:47.643822Z","steps":["trace[132772300] 'agreement among raft nodes before linearized reading' (duration: 384.396075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.643886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.253455Z","time spent":"390.400926ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:17:47.643936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.259169Z","time spent":"384.73047ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-17T15:17:47.643796Z","caller":"traceutil/trace.go:171","msg":"trace[433232741] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"390.183693ms","start":"2026-06-17T15:17:47.253601Z","end":"2026-06-17T15:17:47.643784Z","steps":["trace[433232741] 'process raft request' (duration: 389.788908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.644006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.253591Z","time spent":"390.388187ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:17:47.644026Z","caller":"traceutil/trace.go:171","msg":"trace[333242513] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"386.065117ms","start":"2026-06-17T15:17:47.257873Z","end":"2026-06-17T15:17:47.643938Z","steps":["trace[333242513] 'process raft request' (duration: 385.567801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:17:47.644489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:17:47.257863Z","time spent":"386.343561ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:19:32.904259Z","caller":"traceutil/trace.go:171","msg":"trace[343865999] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"119.282278ms","start":"2026-06-17T15:19:32.78493Z","end":"2026-06-17T15:19:32.904212Z","steps":["trace[343865999] 'process raft request' (duration: 118.712491ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:19:42.169701Z","caller":"traceutil/trace.go:171","msg":"trace[1231486231] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"188.44116ms","start":"2026-06-17T15:19:41.981238Z","end":"2026-06-17T15:19:42.169679Z","steps":["trace[1231486231] 'process raft request' (duration: 188.245785ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:19:43.136425Z","caller":"traceutil/trace.go:171","msg":"trace[1074681684] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"669.171168ms","start":"2026-06-17T15:19:42.467226Z","end":"2026-06-17T15:19:43.136397Z","steps":["trace[1074681684] 'process raft request' (duration: 669.015623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:19:43.13659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:19:42.467197Z","time spent":"669.313371ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:19:43.136984Z","caller":"traceutil/trace.go:171","msg":"trace[1433955573] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2950; }","duration":"358.14212ms","start":"2026-06-17T15:19:42.778818Z","end":"2026-06-17T15:19:43.13696Z","steps":["trace[1433955573] 'read index received' (duration: 357.476022ms)","trace[1433955573] 'applied index is now lower than readState.Index' (duration: 665.028µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:19:43.137097Z","caller":"traceutil/trace.go:171","msg":"trace[359853199] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"185.776004ms","start":"2026-06-17T15:19:42.95131Z","end":"2026-06-17T15:19:43.137086Z","steps":["trace[359853199] 'process raft request' (duration: 185.576768ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:19:43.137251Z","caller":"traceutil/trace.go:171","msg":"trace[1751446573] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"564.38756ms","start":"2026-06-17T15:19:42.572756Z","end":"2026-06-17T15:19:43.137143Z","steps":["trace[1751446573] 'process raft request' (duration: 564.03934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:19:43.137451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.610604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:19:43.137494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:19:42.572733Z","time spent":"564.630868ms","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:19:43.137596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.410484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:19:43.137637Z","caller":"traceutil/trace.go:171","msg":"trace[1579910455] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2841; }","duration":"132.477206ms","start":"2026-06-17T15:19:43.005146Z","end":"2026-06-17T15:19:43.137623Z","steps":["trace[1579910455] 'agreement among raft nodes before linearized reading' (duration: 132.396374ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:19:43.137511Z","caller":"traceutil/trace.go:171","msg":"trace[1976962455] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2841; }","duration":"358.734918ms","start":"2026-06-17T15:19:42.77876Z","end":"2026-06-17T15:19:43.137495Z","steps":["trace[1976962455] 'agreement among raft nodes before linearized reading' (duration: 358.624135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:19:43.137872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:19:42.778736Z","time spent":"359.113778ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:20:06.514563Z","caller":"traceutil/trace.go:171","msg":"trace[769693262] linearizableReadLoop","detail":"{readStateIndex:3088; appliedIndex:3087; }","duration":"122.162931ms","start":"2026-06-17T15:20:06.392328Z","end":"2026-06-17T15:20:06.514491Z","steps":["trace[769693262] 'read index received' (duration: 60.10693ms)","trace[769693262] 'applied index is now lower than readState.Index' (duration: 62.054621ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:20:06.514714Z","caller":"traceutil/trace.go:171","msg":"trace[288484214] transaction","detail":"{read_only:false; response_revision:2971; number_of_response:1; }","duration":"165.222439ms","start":"2026-06-17T15:20:06.349481Z","end":"2026-06-17T15:20:06.514703Z","steps":["trace[288484214] 'process raft request' (duration: 103.054844ms)","trace[288484214] 'compare' (duration: 61.808195ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:20:06.515449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.097196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-17T15:20:06.515481Z","caller":"traceutil/trace.go:171","msg":"trace[1925452078] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2971; }","duration":"123.189698ms","start":"2026-06-17T15:20:06.392285Z","end":"2026-06-17T15:20:06.515474Z","steps":["trace[1925452078] 'agreement among raft nodes before linearized reading' (duration: 123.090186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:17.738321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.051249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:20:17.738431Z","caller":"traceutil/trace.go:171","msg":"trace[1177406934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3029; }","duration":"322.235113ms","start":"2026-06-17T15:20:17.41617Z","end":"2026-06-17T15:20:17.738405Z","steps":["trace[1177406934] 'range keys from in-memory index tree' (duration: 321.469534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:17.738474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:20:17.416152Z","time spent":"322.312115ms","remote":"127.0.0.1:55752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:20:17.738898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.528599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:20:17.739083Z","caller":"traceutil/trace.go:171","msg":"trace[944412942] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:3029; }","duration":"295.798975ms","start":"2026-06-17T15:20:17.443273Z","end":"2026-06-17T15:20:17.739071Z","steps":["trace[944412942] 'count revisions from in-memory index tree' (duration: 295.410115ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:20:17.739029Z","caller":"traceutil/trace.go:171","msg":"trace[316918276] transaction","detail":"{read_only:false; response_revision:3030; number_of_response:1; }","duration":"272.792264ms","start":"2026-06-17T15:20:17.466219Z","end":"2026-06-17T15:20:17.739011Z","steps":["trace[316918276] 'process raft request' (duration: 259.694519ms)","trace[316918276] 'compare' (duration: 12.48996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:20:18.543044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.438839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:20:18.543139Z","caller":"traceutil/trace.go:171","msg":"trace[1518822523] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3030; }","duration":"450.611983ms","start":"2026-06-17T15:20:18.092504Z","end":"2026-06-17T15:20:18.543116Z","steps":["trace[1518822523] 'count revisions from in-memory index tree' (duration: 450.331006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:18.543177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:20:18.09248Z","time spent":"450.687605ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:20:18.544554Z","caller":"traceutil/trace.go:171","msg":"trace[21980349] linearizableReadLoop","detail":"{readStateIndex:3150; appliedIndex:3149; }","duration":"188.069759ms","start":"2026-06-17T15:20:18.356454Z","end":"2026-06-17T15:20:18.544524Z","steps":["trace[21980349] 'read index received' (duration: 187.71647ms)","trace[21980349] 'applied index is now lower than readState.Index' (duration: 351.949µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:20:18.544665Z","caller":"traceutil/trace.go:171","msg":"trace[840780734] transaction","detail":"{read_only:false; response_revision:3031; number_of_response:1; }","duration":"212.560117ms","start":"2026-06-17T15:20:18.332069Z","end":"2026-06-17T15:20:18.544629Z","steps":["trace[840780734] 'process raft request' (duration: 212.168367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:18.544863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.367227ms","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-06-17T15:20:18.54491Z","caller":"traceutil/trace.go:171","msg":"trace[1020484890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3031; }","duration":"188.49242ms","start":"2026-06-17T15:20:18.356403Z","end":"2026-06-17T15:20:18.544895Z","steps":["trace[1020484890] 'agreement among raft nodes before linearized reading' (duration: 188.273525ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:20:18.552177Z","caller":"traceutil/trace.go:171","msg":"trace[554560630] transaction","detail":"{read_only:false; response_revision:3032; number_of_response:1; }","duration":"164.923016ms","start":"2026-06-17T15:20:18.387233Z","end":"2026-06-17T15:20:18.552156Z","steps":["trace[554560630] 'process raft request' (duration: 164.548116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:18.55236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.728979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:20:18.552581Z","caller":"traceutil/trace.go:171","msg":"trace[1467644297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3032; }","duration":"135.038417ms","start":"2026-06-17T15:20:18.417521Z","end":"2026-06-17T15:20:18.55256Z","steps":["trace[1467644297] 'agreement among raft nodes before linearized reading' (duration: 134.684838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:20:18.554585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.714126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-17T15:20:18.554631Z","caller":"traceutil/trace.go:171","msg":"trace[493962734] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3032; }","duration":"167.817748ms","start":"2026-06-17T15:20:18.386803Z","end":"2026-06-17T15:20:18.554621Z","steps":["trace[493962734] 'agreement among raft nodes before linearized reading' (duration: 165.288435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:52.139254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.244418ms","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-06-17T15:23:52.13935Z","caller":"traceutil/trace.go:171","msg":"trace[1336293529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4317; }","duration":"167.400939ms","start":"2026-06-17T15:23:51.971929Z","end":"2026-06-17T15:23:52.139329Z","steps":["trace[1336293529] 'range keys from in-memory index tree' (duration: 166.992473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:52.139332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.686179ms","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-06-17T15:23:52.139424Z","caller":"traceutil/trace.go:171","msg":"trace[582802473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4317; }","duration":"166.834782ms","start":"2026-06-17T15:23:51.972566Z","end":"2026-06-17T15:23:52.139401Z","steps":["trace[582802473] 'range keys from in-memory index tree' (duration: 166.434784ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.142452Z","caller":"traceutil/trace.go:171","msg":"trace[159895838] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"110.55017ms","start":"2026-06-17T15:24:12.03187Z","end":"2026-06-17T15:24:12.14242Z","steps":["trace[159895838] 'process raft request' (duration: 84.094493ms)","trace[159895838] 'compare' (duration: 26.214204ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:12.154591Z","caller":"traceutil/trace.go:171","msg":"trace[1946288259] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"121.645523ms","start":"2026-06-17T15:24:12.032918Z","end":"2026-06-17T15:24:12.154564Z","steps":["trace[1946288259] 'process raft request' (duration: 121.46125ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.15462Z","caller":"traceutil/trace.go:171","msg":"trace[1894696784] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"121.671563ms","start":"2026-06-17T15:24:12.03292Z","end":"2026-06-17T15:24:12.154591Z","steps":["trace[1894696784] 'process raft request' (duration: 121.575912ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.277778Z","caller":"traceutil/trace.go:171","msg":"trace[1952825094] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"133.384315ms","start":"2026-06-17T15:24:12.144371Z","end":"2026-06-17T15:24:12.277756Z","steps":["trace[1952825094] 'process raft request' (duration: 133.264844ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.27835Z","caller":"traceutil/trace.go:171","msg":"trace[1188992296] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4730; }","duration":"123.779396ms","start":"2026-06-17T15:24:12.154556Z","end":"2026-06-17T15:24:12.278335Z","steps":["trace[1188992296] 'read index received' (duration: 123.773305ms)","trace[1188992296] 'applied index is now lower than readState.Index' (duration: 4.631µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:12.278508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.845851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:12.278547Z","caller":"traceutil/trace.go:171","msg":"trace[386416640] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4543; }","duration":"124.929762ms","start":"2026-06-17T15:24:12.153603Z","end":"2026-06-17T15:24:12.278533Z","steps":["trace[386416640] 'agreement among raft nodes before linearized reading' (duration: 124.819141ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.320118Z","caller":"traceutil/trace.go:171","msg":"trace[1880820054] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"162.135267ms","start":"2026-06-17T15:24:12.157934Z","end":"2026-06-17T15:24:12.32007Z","steps":["trace[1880820054] 'process raft request' (duration: 161.498387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:12.320741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.682085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:12.320782Z","caller":"traceutil/trace.go:171","msg":"trace[390943626] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4546; }","duration":"119.793857ms","start":"2026-06-17T15:24:12.200975Z","end":"2026-06-17T15:24:12.320769Z","steps":["trace[390943626] 'agreement among raft nodes before linearized reading' (duration: 119.631294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:12.656234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.932738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:10568"} {"level":"info","ts":"2026-06-17T15:24:12.656353Z","caller":"traceutil/trace.go:171","msg":"trace[764107730] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4623; }","duration":"105.092832ms","start":"2026-06-17T15:24:12.551236Z","end":"2026-06-17T15:24:12.656329Z","steps":["trace[764107730] 'agreement among raft nodes before linearized reading' (duration: 104.838908ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:13.45174Z","caller":"traceutil/trace.go:171","msg":"trace[490805270] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"135.126579ms","start":"2026-06-17T15:24:13.316578Z","end":"2026-06-17T15:24:13.451704Z","steps":["trace[490805270] 'process raft request' (duration: 61.021004ms)","trace[490805270] 'compare' (duration: 73.455415ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:14.322723Z","caller":"traceutil/trace.go:171","msg":"trace[303771205] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4763; }","duration":"108.080311ms","start":"2026-06-17T15:24:14.2146Z","end":"2026-06-17T15:24:14.32268Z","steps":["trace[303771205] 'process raft request' (duration: 87.48761ms)","trace[303771205] 'compare' (duration: 20.268306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:14.562009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.909065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:14.562358Z","caller":"traceutil/trace.go:171","msg":"trace[602069584] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"136.938384ms","start":"2026-06-17T15:24:14.425392Z","end":"2026-06-17T15:24:14.56233Z","steps":["trace[602069584] 'process raft request' (duration: 136.813002ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:14.562365Z","caller":"traceutil/trace.go:171","msg":"trace[1370302928] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"137.167327ms","start":"2026-06-17T15:24:14.425131Z","end":"2026-06-17T15:24:14.562298Z","steps":["trace[1370302928] 'process raft request' (duration: 137.011845ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:14.562309Z","caller":"traceutil/trace.go:171","msg":"trace[187805841] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"137.698685ms","start":"2026-06-17T15:24:14.424561Z","end":"2026-06-17T15:24:14.56226Z","steps":["trace[187805841] 'process raft request' (duration: 19.054759ms)","trace[187805841] 'compare' (duration: 117.686662ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:14.562795Z","caller":"traceutil/trace.go:171","msg":"trace[492375122] linearizableReadLoop","detail":"{readStateIndex:4963; appliedIndex:4960; }","duration":"131.596436ms","start":"2026-06-17T15:24:14.431134Z","end":"2026-06-17T15:24:14.562731Z","steps":["trace[492375122] 'read index received' (duration: 12.500763ms)","trace[492375122] 'applied index is now lower than readState.Index' (duration: 119.091933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:14.562906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.753788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:14.562971Z","caller":"traceutil/trace.go:171","msg":"trace[1354984127] range","detail":"{range_begin:/registry/clusterrolebindings/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:4770; }","duration":"131.836349ms","start":"2026-06-17T15:24:14.431116Z","end":"2026-06-17T15:24:14.562953Z","steps":["trace[1354984127] 'agreement among raft nodes before linearized reading' (duration: 131.701477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:16.788367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.025043ms","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-06-17T15:24:16.788458Z","caller":"traceutil/trace.go:171","msg":"trace[2033194435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4809; }","duration":"220.157515ms","start":"2026-06-17T15:24:16.568281Z","end":"2026-06-17T15:24:16.788439Z","steps":["trace[2033194435] 'range keys from in-memory index tree' (duration: 219.85525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:16.788464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.387891ms","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-06-17T15:24:16.788534Z","caller":"traceutil/trace.go:171","msg":"trace[850724373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4809; }","duration":"216.490592ms","start":"2026-06-17T15:24:16.572026Z","end":"2026-06-17T15:24:16.788517Z","steps":["trace[850724373] 'range keys from in-memory index tree' (duration: 216.253069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:16.788917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.907237ms","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-06-17T15:24:16.788981Z","caller":"traceutil/trace.go:171","msg":"trace[1782846913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4809; }","duration":"221.010258ms","start":"2026-06-17T15:24:16.567958Z","end":"2026-06-17T15:24:16.788969Z","steps":["trace[1782846913] 'range keys from in-memory index tree' (duration: 220.773494ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:16.790437Z","caller":"traceutil/trace.go:171","msg":"trace[1127517801] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"181.775775ms","start":"2026-06-17T15:24:16.608638Z","end":"2026-06-17T15:24:16.790414Z","steps":["trace[1127517801] 'process raft request' (duration: 181.38433ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:18.185888Z","caller":"traceutil/trace.go:171","msg":"trace[376014806] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"161.597245ms","start":"2026-06-17T15:24:18.024247Z","end":"2026-06-17T15:24:18.185845Z","steps":["trace[376014806] 'process raft request' (duration: 136.807104ms)","trace[376014806] 'compare' (duration: 24.464326ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:18.188775Z","caller":"traceutil/trace.go:171","msg":"trace[1194125137] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5077; }","duration":"129.520308ms","start":"2026-06-17T15:24:18.059234Z","end":"2026-06-17T15:24:18.188755Z","steps":["trace[1194125137] 'read index received' (duration: 101.736034ms)","trace[1194125137] 'applied index is now lower than readState.Index' (duration: 27.783324ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:18.188961Z","caller":"traceutil/trace.go:171","msg":"trace[1496616318] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"149.200235ms","start":"2026-06-17T15:24:18.03975Z","end":"2026-06-17T15:24:18.18895Z","steps":["trace[1496616318] 'process raft request' (duration: 148.914771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:18.189281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.018326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83d13815-b65b-46b0-aa3e-7955c54ef73e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-06-17T15:24:18.189365Z","caller":"traceutil/trace.go:171","msg":"trace[717946393] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83d13815-b65b-46b0-aa3e-7955c54ef73e; range_end:; response_count:1; response_revision:4883; }","duration":"130.145647ms","start":"2026-06-17T15:24:18.059201Z","end":"2026-06-17T15:24:18.189346Z","steps":["trace[717946393] 'agreement among raft nodes before linearized reading' (duration: 129.874443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:18.190595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.069516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:24:18.190774Z","caller":"traceutil/trace.go:171","msg":"trace[147453898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4883; }","duration":"106.443781ms","start":"2026-06-17T15:24:18.084305Z","end":"2026-06-17T15:24:18.190749Z","steps":["trace[147453898] 'agreement among raft nodes before linearized reading' (duration: 104.97477ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:18.447026Z","caller":"traceutil/trace.go:171","msg":"trace[1160418218] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"243.377677ms","start":"2026-06-17T15:24:18.203619Z","end":"2026-06-17T15:24:18.446997Z","steps":["trace[1160418218] 'process raft request' (duration: 243.218025ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:19.211433Z","caller":"traceutil/trace.go:171","msg":"trace[2037006384] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"189.57935ms","start":"2026-06-17T15:24:19.021816Z","end":"2026-06-17T15:24:19.211395Z","steps":["trace[2037006384] 'process raft request' (duration: 189.394887ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:19.665569Z","caller":"traceutil/trace.go:171","msg":"trace[1575421088] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5096; }","duration":"147.014111ms","start":"2026-06-17T15:24:19.518519Z","end":"2026-06-17T15:24:19.665533Z","steps":["trace[1575421088] 'read index received' (duration: 146.897279ms)","trace[1575421088] 'applied index is now lower than readState.Index' (duration: 115.632µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:19.665784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.240644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:19.665749Z","caller":"traceutil/trace.go:171","msg":"trace[200444233] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"173.275853ms","start":"2026-06-17T15:24:19.492424Z","end":"2026-06-17T15:24:19.6657Z","steps":["trace[200444233] 'process raft request' (duration: 172.962049ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:19.665821Z","caller":"traceutil/trace.go:171","msg":"trace[421808805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4901; }","duration":"147.350316ms","start":"2026-06-17T15:24:19.518462Z","end":"2026-06-17T15:24:19.665812Z","steps":["trace[421808805] 'agreement among raft nodes before linearized reading' (duration: 147.232164ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:20.883854Z","caller":"traceutil/trace.go:171","msg":"trace[2084038388] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5108; }","duration":"101.904131ms","start":"2026-06-17T15:24:20.781915Z","end":"2026-06-17T15:24:20.883819Z","steps":["trace[2084038388] 'read index received' (duration: 62.093782ms)","trace[2084038388] 'applied index is now lower than readState.Index' (duration: 39.808929ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:20.883883Z","caller":"traceutil/trace.go:171","msg":"trace[1918952019] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"124.977457ms","start":"2026-06-17T15:24:20.758857Z","end":"2026-06-17T15:24:20.883834Z","steps":["trace[1918952019] 'process raft request' (duration: 124.617132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:20.884051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.087555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:20.884116Z","caller":"traceutil/trace.go:171","msg":"trace[332832064] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4915; }","duration":"102.235827ms","start":"2026-06-17T15:24:20.781862Z","end":"2026-06-17T15:24:20.884097Z","steps":["trace[332832064] 'agreement among raft nodes before linearized reading' (duration: 102.077035ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:22.872942Z","caller":"traceutil/trace.go:171","msg":"trace[394638417] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"316.208651ms","start":"2026-06-17T15:24:22.556704Z","end":"2026-06-17T15:24:22.872913Z","steps":["trace[394638417] 'process raft request' (duration: 315.935926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:22.873145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:22.556673Z","time spent":"316.364853ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:26.705963Z","caller":"traceutil/trace.go:171","msg":"trace[27302359] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"207.224317ms","start":"2026-06-17T15:24:26.498703Z","end":"2026-06-17T15:24:26.705927Z","steps":["trace[27302359] 'process raft request' (duration: 206.965783ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:26.717833Z","caller":"traceutil/trace.go:171","msg":"trace[217020806] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"210.455303ms","start":"2026-06-17T15:24:26.507344Z","end":"2026-06-17T15:24:26.7178Z","steps":["trace[217020806] 'process raft request' (duration: 210.264151ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:27.749261Z","caller":"traceutil/trace.go:171","msg":"trace[667770870] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"239.558831ms","start":"2026-06-17T15:24:27.509676Z","end":"2026-06-17T15:24:27.749234Z","steps":["trace[667770870] 'process raft request' (duration: 239.359188ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:27.768336Z","caller":"traceutil/trace.go:171","msg":"trace[904510013] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"250.156674ms","start":"2026-06-17T15:24:27.518148Z","end":"2026-06-17T15:24:27.768305Z","steps":["trace[904510013] 'process raft request' (duration: 249.945741ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:33.042207Z","caller":"traceutil/trace.go:171","msg":"trace[448003175] linearizableReadLoop","detail":"{readStateIndex:5323; appliedIndex:5322; }","duration":"213.358138ms","start":"2026-06-17T15:24:32.828825Z","end":"2026-06-17T15:24:33.042183Z","steps":["trace[448003175] 'read index received' (duration: 213.133815ms)","trace[448003175] 'applied index is now lower than readState.Index' (duration: 222.853µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:33.042313Z","caller":"traceutil/trace.go:171","msg":"trace[1931165252] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"476.774427ms","start":"2026-06-17T15:24:32.56551Z","end":"2026-06-17T15:24:33.042284Z","steps":["trace[1931165252] 'process raft request' (duration: 476.467613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.042708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.672953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T15:24:33.042763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:32.565493Z","time spent":"477.114292ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:33.042823Z","caller":"traceutil/trace.go:171","msg":"trace[471889647] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5121; }","duration":"214.068469ms","start":"2026-06-17T15:24:32.828739Z","end":"2026-06-17T15:24:33.042808Z","steps":["trace[471889647] 'agreement among raft nodes before linearized reading' (duration: 213.581482ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:33.356926Z","caller":"traceutil/trace.go:171","msg":"trace[1973483884] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5323; }","duration":"314.590531ms","start":"2026-06-17T15:24:33.042293Z","end":"2026-06-17T15:24:33.356883Z","steps":["trace[1973483884] 'read index received' (duration: 314.130284ms)","trace[1973483884] 'applied index is now lower than readState.Index' (duration: 458.897µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:33.357214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.794492ms","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-06-17T15:24:33.357344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.488012ms","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-06-17T15:24:33.357406Z","caller":"traceutil/trace.go:171","msg":"trace[968246579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5123; }","duration":"365.543423ms","start":"2026-06-17T15:24:32.991848Z","end":"2026-06-17T15:24:33.357391Z","steps":["trace[968246579] 'agreement among raft nodes before linearized reading' (duration: 365.423891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.357462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:32.991833Z","time spent":"365.620144ms","remote":"127.0.0.1:56008","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-06-17T15:24:33.357579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.066706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjjn6.18b9e7af41929c84\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-17T15:24:33.357633Z","caller":"traceutil/trace.go:171","msg":"trace[298531216] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjjn6.18b9e7af41929c84; range_end:; response_count:1; response_revision:5123; }","duration":"207.206308ms","start":"2026-06-17T15:24:33.150412Z","end":"2026-06-17T15:24:33.357618Z","steps":["trace[298531216] 'agreement among raft nodes before linearized reading' (duration: 206.991455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.357521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.727001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:24:33.357625Z","caller":"traceutil/trace.go:171","msg":"trace[1526268346] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"414.135543ms","start":"2026-06-17T15:24:32.943035Z","end":"2026-06-17T15:24:33.357171Z","steps":["trace[1526268346] 'process raft request' (duration: 413.504964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.359306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:32.943005Z","time spent":"416.139921ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:33.357652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.432677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dzwfx\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-06-17T15:24:33.359839Z","caller":"traceutil/trace.go:171","msg":"trace[295397084] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dzwfx; range_end:; response_count:1; response_revision:5123; }","duration":"311.645929ms","start":"2026-06-17T15:24:33.048175Z","end":"2026-06-17T15:24:33.359821Z","steps":["trace[295397084] 'agreement among raft nodes before linearized reading' (duration: 309.203144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.359885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:33.048105Z","time spent":"311.76539ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5171,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dzwfx\" "} {"level":"info","ts":"2026-06-17T15:24:33.357686Z","caller":"traceutil/trace.go:171","msg":"trace[1002482431] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5123; }","duration":"167.948874ms","start":"2026-06-17T15:24:33.189722Z","end":"2026-06-17T15:24:33.357671Z","steps":["trace[1002482431] 'agreement among raft nodes before linearized reading' (duration: 167.66585ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:33.357804Z","caller":"traceutil/trace.go:171","msg":"trace[836689207] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"330.641631ms","start":"2026-06-17T15:24:33.027138Z","end":"2026-06-17T15:24:33.35778Z","steps":["trace[836689207] 'process raft request' (duration: 329.661897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.360346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:33.027123Z","time spent":"333.187308ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:33.357245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.282756ms","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-06-17T15:24:33.360625Z","caller":"traceutil/trace.go:171","msg":"trace[1290953727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5123; }","duration":"342.715825ms","start":"2026-06-17T15:24:33.017898Z","end":"2026-06-17T15:24:33.360613Z","steps":["trace[1290953727] 'agreement among raft nodes before linearized reading' (duration: 339.191154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.360662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:33.017887Z","time spent":"342.764435ms","remote":"127.0.0.1:56008","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-06-17T15:24:33.357805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.664475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-17T15:24:33.360855Z","caller":"traceutil/trace.go:171","msg":"trace[1199847262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5123; }","duration":"336.732809ms","start":"2026-06-17T15:24:33.024106Z","end":"2026-06-17T15:24:33.360839Z","steps":["trace[1199847262] 'agreement among raft nodes before linearized reading' (duration: 332.958525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.360891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:33.024096Z","time spent":"336.783089ms","remote":"127.0.0.1:56008","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-06-17T15:24:33.357372Z","caller":"traceutil/trace.go:171","msg":"trace[654200745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5123; }","duration":"332.958835ms","start":"2026-06-17T15:24:33.024318Z","end":"2026-06-17T15:24:33.357277Z","steps":["trace[654200745] 'agreement among raft nodes before linearized reading' (duration: 332.667321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:33.361103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:33.024278Z","time spent":"336.81532ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T15:24:34.193429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.46299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:34.193827Z","caller":"traceutil/trace.go:171","msg":"trace[858432005] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"266.313034ms","start":"2026-06-17T15:24:33.927478Z","end":"2026-06-17T15:24:34.193791Z","steps":["trace[858432005] 'process raft request' (duration: 136.360178ms)","trace[858432005] 'compare' (duration: 129.063432ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:34.194147Z","caller":"traceutil/trace.go:171","msg":"trace[1796158104] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"255.789823ms","start":"2026-06-17T15:24:33.938319Z","end":"2026-06-17T15:24:34.194109Z","steps":["trace[1796158104] 'process raft request' (duration: 255.370897ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:34.707642Z","caller":"traceutil/trace.go:171","msg":"trace[992689137] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"204.923032ms","start":"2026-06-17T15:24:34.502673Z","end":"2026-06-17T15:24:34.707596Z","steps":["trace[992689137] 'process raft request' (duration: 204.613488ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:36.048388Z","caller":"traceutil/trace.go:171","msg":"trace[684823866] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"109.660412ms","start":"2026-06-17T15:24:35.938701Z","end":"2026-06-17T15:24:36.048361Z","steps":["trace[684823866] 'process raft request' (duration: 109.46217ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:43.153954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840} {"level":"info","ts":"2026-06-17T15:24:43.23626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"80.143943ms","hash":3358490029,"current-db-size-bytes":51056640,"current-db-size":"51 MB","current-db-size-in-use-bytes":27111424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-17T15:24:43.236346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3358490029,"revision":2840,"compact-revision":-1} {"level":"info","ts":"2026-06-17T15:24:50.1868Z","caller":"traceutil/trace.go:171","msg":"trace[486666207] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"566.622341ms","start":"2026-06-17T15:24:49.620139Z","end":"2026-06-17T15:24:50.186762Z","steps":["trace[486666207] 'process raft request' (duration: 566.398708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.187123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.620117Z","time spent":"566.786034ms","remote":"127.0.0.1:55946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:50.551672Z","caller":"traceutil/trace.go:171","msg":"trace[1561082286] linearizableReadLoop","detail":"{readStateIndex:5542; appliedIndex:5537; }","duration":"781.309462ms","start":"2026-06-17T15:24:49.770343Z","end":"2026-06-17T15:24:50.551652Z","steps":["trace[1561082286] 'read index received' (duration: 416.154729ms)","trace[1561082286] 'applied index is now lower than readState.Index' (duration: 365.153793ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:50.551833Z","caller":"traceutil/trace.go:171","msg":"trace[1507360328] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"447.009792ms","start":"2026-06-17T15:24:50.104813Z","end":"2026-06-17T15:24:50.551822Z","steps":["trace[1507360328] 'process raft request' (duration: 446.799299ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:50.551876Z","caller":"traceutil/trace.go:171","msg":"trace[1425629440] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"920.073659ms","start":"2026-06-17T15:24:49.631796Z","end":"2026-06-17T15:24:50.551869Z","steps":["trace[1425629440] 'process raft request' (duration: 919.768895ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:50.551743Z","caller":"traceutil/trace.go:171","msg":"trace[1491034980] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"920.599307ms","start":"2026-06-17T15:24:49.631116Z","end":"2026-06-17T15:24:50.551715Z","steps":["trace[1491034980] 'process raft request' (duration: 920.420504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.551978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.631784Z","time spent":"920.127459ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:50.552034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.631105Z","time spent":"920.877081ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:50.552041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:50.104782Z","time spent":"447.096474ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:50.551829Z","caller":"traceutil/trace.go:171","msg":"trace[1895430075] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"919.929197ms","start":"2026-06-17T15:24:49.631876Z","end":"2026-06-17T15:24:50.551805Z","steps":["trace[1895430075] 'process raft request' (duration: 919.711274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.552179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.631865Z","time spent":"920.291082ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:50.552269Z","caller":"traceutil/trace.go:171","msg":"trace[1058258004] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"921.439818ms","start":"2026-06-17T15:24:49.630822Z","end":"2026-06-17T15:24:50.552262Z","steps":["trace[1058258004] 'process raft request' (duration: 920.586727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.55231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.630789Z","time spent":"921.50102ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:50.552473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.111743ms","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-06-17T15:24:50.552605Z","caller":"traceutil/trace.go:171","msg":"trace[514692488] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5333; }","duration":"782.192274ms","start":"2026-06-17T15:24:49.770334Z","end":"2026-06-17T15:24:50.552527Z","steps":["trace[514692488] 'agreement among raft nodes before linearized reading' (duration: 781.829459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.55267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:49.770256Z","time spent":"782.404837ms","remote":"127.0.0.1:55920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-17T15:24:50.608827Z","caller":"traceutil/trace.go:171","msg":"trace[2048952740] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"308.678953ms","start":"2026-06-17T15:24:50.300122Z","end":"2026-06-17T15:24:50.608801Z","steps":["trace[2048952740] 'process raft request' (duration: 308.501051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.60899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:50.300086Z","time spent":"308.821866ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:50.609224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.03338ms","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":"warn","ts":"2026-06-17T15:24:50.609239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.850262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:50.609288Z","caller":"traceutil/trace.go:171","msg":"trace[2099761838] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5335; }","duration":"206.123281ms","start":"2026-06-17T15:24:50.40315Z","end":"2026-06-17T15:24:50.609273Z","steps":["trace[2099761838] 'agreement among raft nodes before linearized reading' (duration: 206.030119ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:50.609309Z","caller":"traceutil/trace.go:171","msg":"trace[1903417919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5335; }","duration":"199.976284ms","start":"2026-06-17T15:24:50.409315Z","end":"2026-06-17T15:24:50.609292Z","steps":["trace[1903417919] 'agreement among raft nodes before linearized reading' (duration: 199.772071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.609373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.646625ms","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-06-17T15:24:50.6095Z","caller":"traceutil/trace.go:171","msg":"trace[1023979584] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5335; }","duration":"215.847127ms","start":"2026-06-17T15:24:50.393625Z","end":"2026-06-17T15:24:50.609472Z","steps":["trace[1023979584] 'agreement among raft nodes before linearized reading' (duration: 215.629964ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:50.609684Z","caller":"traceutil/trace.go:171","msg":"trace[153454553] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"308.021774ms","start":"2026-06-17T15:24:50.301641Z","end":"2026-06-17T15:24:50.609662Z","steps":["trace[153454553] 'process raft request' (duration: 307.119891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:50.609853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:50.301604Z","time spent":"308.182947ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:50.727269Z","caller":"traceutil/trace.go:171","msg":"trace[2085576010] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"112.754588ms","start":"2026-06-17T15:24:50.614463Z","end":"2026-06-17T15:24:50.727218Z","steps":["trace[2085576010] 'process raft request' (duration: 93.718189ms)","trace[2085576010] 'compare' (duration: 18.661554ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:50.727279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:50.727392Z","caller":"traceutil/trace.go:171","msg":"trace[1871267924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5337; }","duration":"116.762314ms","start":"2026-06-17T15:24:50.610603Z","end":"2026-06-17T15:24:50.727366Z","steps":["trace[1871267924] 'agreement among raft nodes before linearized reading' (duration: 116.4544ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:51.809168Z","caller":"traceutil/trace.go:171","msg":"trace[1862204261] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"103.485055ms","start":"2026-06-17T15:24:51.705661Z","end":"2026-06-17T15:24:51.809146Z","steps":["trace[1862204261] 'process raft request' (duration: 103.341103ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:24.847753Z","caller":"traceutil/trace.go:171","msg":"trace[201780570] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"130.201121ms","start":"2026-06-17T15:25:24.717524Z","end":"2026-06-17T15:25:24.847725Z","steps":["trace[201780570] 'process raft request' (duration: 130.037759ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:24.969743Z","caller":"traceutil/trace.go:171","msg":"trace[2124880110] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"230.145594ms","start":"2026-06-17T15:25:24.739563Z","end":"2026-06-17T15:25:24.969708Z","steps":["trace[2124880110] 'process raft request' (duration: 228.979589ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:24.970032Z","caller":"traceutil/trace.go:171","msg":"trace[1450365874] linearizableReadLoop","detail":"{readStateIndex:5864; appliedIndex:5861; }","duration":"215.545242ms","start":"2026-06-17T15:25:24.754475Z","end":"2026-06-17T15:25:24.97002Z","steps":["trace[1450365874] 'read index received' (duration: 93.112038ms)","trace[1450365874] 'applied index is now lower than readState.Index' (duration: 122.431444ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:25:24.970165Z","caller":"traceutil/trace.go:171","msg":"trace[1536705209] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"229.925132ms","start":"2026-06-17T15:25:24.740232Z","end":"2026-06-17T15:25:24.970157Z","steps":["trace[1536705209] 'process raft request' (duration: 229.434195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:25:24.970361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.860027ms","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-06-17T15:25:24.970388Z","caller":"traceutil/trace.go:171","msg":"trace[750273519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5645; }","duration":"215.962069ms","start":"2026-06-17T15:25:24.754418Z","end":"2026-06-17T15:25:24.97038Z","steps":["trace[750273519] 'agreement among raft nodes before linearized reading' (duration: 215.807015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:25:24.970544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.510298ms","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-06-17T15:25:24.970562Z","caller":"traceutil/trace.go:171","msg":"trace[1739071503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5645; }","duration":"206.542448ms","start":"2026-06-17T15:25:24.764015Z","end":"2026-06-17T15:25:24.970557Z","steps":["trace[1739071503] 'agreement among raft nodes before linearized reading' (duration: 206.456507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:25:24.970546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.604978ms","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-06-17T15:25:24.97063Z","caller":"traceutil/trace.go:171","msg":"trace[586539277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5645; }","duration":"211.733929ms","start":"2026-06-17T15:25:24.758866Z","end":"2026-06-17T15:25:24.9706Z","steps":["trace[586539277] 'agreement among raft nodes before linearized reading' (duration: 211.491576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:25:24.970846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.209203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-17T15:25:24.970932Z","caller":"traceutil/trace.go:171","msg":"trace[2050251358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5645; }","duration":"211.304834ms","start":"2026-06-17T15:25:24.759606Z","end":"2026-06-17T15:25:24.97091Z","steps":["trace[2050251358] 'agreement among raft nodes before linearized reading' (duration: 211.108761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:25:35.410827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.785268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-06-17T15:25:35.410955Z","caller":"traceutil/trace.go:171","msg":"trace[567515753] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5707; }","duration":"125.990371ms","start":"2026-06-17T15:25:35.284936Z","end":"2026-06-17T15:25:35.410926Z","steps":["trace[567515753] 'range keys from in-memory index tree' (duration: 125.486404ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:35.659454Z","caller":"traceutil/trace.go:171","msg":"trace[1362934073] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"160.379184ms","start":"2026-06-17T15:25:35.499033Z","end":"2026-06-17T15:25:35.659412Z","steps":["trace[1362934073] 'process raft request' (duration: 160.04529ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:49.43455Z","caller":"traceutil/trace.go:171","msg":"trace[873740367] transaction","detail":"{read_only:false; response_revision:5807; number_of_response:1; }","duration":"145.426482ms","start":"2026-06-17T15:25:49.28906Z","end":"2026-06-17T15:25:49.434486Z","steps":["trace[873740367] 'process raft request' (duration: 145.050367ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:25:49.434648Z","caller":"traceutil/trace.go:171","msg":"trace[643452795] transaction","detail":"{read_only:false; response_revision:5808; number_of_response:1; }","duration":"144.284188ms","start":"2026-06-17T15:25:49.290335Z","end":"2026-06-17T15:25:49.434619Z","steps":["trace[643452795] 'process raft request' (duration: 143.973163ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:35.875348Z","caller":"traceutil/trace.go:171","msg":"trace[152018093] transaction","detail":"{read_only:false; response_revision:6952; number_of_response:1; }","duration":"171.876421ms","start":"2026-06-17T15:28:35.703437Z","end":"2026-06-17T15:28:35.875313Z","steps":["trace[152018093] 'process raft request' (duration: 171.645653ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:35.883066Z","caller":"traceutil/trace.go:171","msg":"trace[1583593230] linearizableReadLoop","detail":"{readStateIndex:7216; appliedIndex:7214; }","duration":"111.050554ms","start":"2026-06-17T15:28:35.772Z","end":"2026-06-17T15:28:35.883051Z","steps":["trace[1583593230] 'read index received' (duration: 103.122129ms)","trace[1583593230] 'applied index is now lower than readState.Index' (duration: 7.927235ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:28:35.883168Z","caller":"traceutil/trace.go:171","msg":"trace[43379239] transaction","detail":"{read_only:false; response_revision:6953; number_of_response:1; }","duration":"152.433166ms","start":"2026-06-17T15:28:35.730679Z","end":"2026-06-17T15:28:35.883112Z","steps":["trace[43379239] 'process raft request' (duration: 152.205388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:28:35.883241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.224519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.236\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:28:35.88341Z","caller":"traceutil/trace.go:171","msg":"trace[703204178] range","detail":"{range_begin:/registry/masterleases/199.19.213.236; range_end:; response_count:1; response_revision:6954; }","duration":"111.399985ms","start":"2026-06-17T15:28:35.771971Z","end":"2026-06-17T15:28:35.883371Z","steps":["trace[703204178] 'agreement among raft nodes before linearized reading' (duration: 111.132756ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:44.63668Z","caller":"traceutil/trace.go:171","msg":"trace[60108218] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"201.491125ms","start":"2026-06-17T15:28:44.435148Z","end":"2026-06-17T15:28:44.63664Z","steps":["trace[60108218] 'process raft request' (duration: 201.298969ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:44.662998Z","caller":"traceutil/trace.go:171","msg":"trace[1430609567] transaction","detail":"{read_only:false; response_revision:7005; number_of_response:1; }","duration":"225.569716ms","start":"2026-06-17T15:28:44.437404Z","end":"2026-06-17T15:28:44.662973Z","steps":["trace[1430609567] 'process raft request' (duration: 225.359629ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:48.071831Z","caller":"traceutil/trace.go:171","msg":"trace[1745351288] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"137.142921ms","start":"2026-06-17T15:28:47.934649Z","end":"2026-06-17T15:28:48.071792Z","steps":["trace[1745351288] 'process raft request' (duration: 136.901372ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:48.072202Z","caller":"traceutil/trace.go:171","msg":"trace[972603176] transaction","detail":"{read_only:false; response_revision:7022; number_of_response:1; }","duration":"136.306195ms","start":"2026-06-17T15:28:47.935846Z","end":"2026-06-17T15:28:48.072152Z","steps":["trace[972603176] 'process raft request' (duration: 135.887692ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:08.772563Z","caller":"traceutil/trace.go:171","msg":"trace[662430979] linearizableReadLoop","detail":"{readStateIndex:7455; appliedIndex:7454; }","duration":"120.391145ms","start":"2026-06-17T15:29:08.651969Z","end":"2026-06-17T15:29:08.77236Z","steps":["trace[662430979] 'read index received' (duration: 86.452223ms)","trace[662430979] 'applied index is now lower than readState.Index' (duration: 33.937412ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:29:08.772831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.825569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com\" ","response":"range_response_count:1 size:7109"} {"level":"info","ts":"2026-06-17T15:29:08.772888Z","caller":"traceutil/trace.go:171","msg":"trace[632911174] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com; range_end:; response_count:1; response_revision:7181; }","duration":"120.930371ms","start":"2026-06-17T15:29:08.651942Z","end":"2026-06-17T15:29:08.772873Z","steps":["trace[632911174] 'agreement among raft nodes before linearized reading' (duration: 120.731305ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:08.773351Z","caller":"traceutil/trace.go:171","msg":"trace[954482959] transaction","detail":"{read_only:false; response_revision:7181; number_of_response:1; }","duration":"121.683644ms","start":"2026-06-17T15:29:08.651584Z","end":"2026-06-17T15:29:08.773268Z","steps":["trace[954482959] 'process raft request' (duration: 86.67166ms)","trace[954482959] 'compare' (duration: 33.667974ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:29:43.160524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5271} {"level":"info","ts":"2026-06-17T15:29:43.262227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5271,"took":"99.422264ms","hash":207725663,"current-db-size-bytes":51056640,"current-db-size":"51 MB","current-db-size-in-use-bytes":19161088,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-17T15:29:43.262369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":207725663,"revision":5271,"compact-revision":2840}