{"level":"warn","ts":"2026-04-28T17:18:58.758017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T17:18:58.758093Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224: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.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T17:18:58.758152Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T17:18:58.758163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-28T17:18:58.758181Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T17:18:58.758768Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-04-28T17:18:58.75884Z","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.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T17:18:59.639705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"680.64305ms"} {"level":"info","ts":"2026-04-28T17:18:59.843312Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-04-28T17:18:59.84341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T17:18:59.843441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-04-28T17:18:59.843451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T17:18:59.843462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-04-28T17:18:59.843503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-04-28T17:18:59.852123Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T17:18:59.854818Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T17:18:59.856921Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T17:18:59.859512Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T17:18:59.860267Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T17:18:59.860437Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T17:18:59.860455Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T17:18:59.860169Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T17:18:59.861659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-04-28T17:18:59.861822Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-28T17:18:59.865012Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T17:18:59.865089Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-28T17:18:59.865135Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-28T17:18:59.865415Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T17:18:59.865495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T17:19:00.844865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T17:19:00.844963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T17:19:00.845034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-04-28T17:19:00.845061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-04-28T17:19:00.845079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-28T17:19:00.845098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-04-28T17:19:00.845118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-28T17:19:00.863237Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T17:19:00.863243Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T17:19:00.863251Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:19:00.863299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T17:19:00.863681Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T17:19:00.863772Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T17:19:00.865171Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:19:00.865273Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:19:00.865303Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:19:00.868121Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T17:19:00.868822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-04-28T17:19:05.749069Z","caller":"traceutil/trace.go:171","msg":"trace[886395614] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"154.871123ms","start":"2026-04-28T17:19:05.594169Z","end":"2026-04-28T17:19:05.74904Z","steps":["trace[886395614] 'read index received' (duration: 154.736038ms)","trace[886395614] 'applied index is now lower than readState.Index' (duration: 133.995µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:05.749222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.158111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:05.749349Z","caller":"traceutil/trace.go:171","msg":"trace[61189387] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:263; }","duration":"162.295196ms","start":"2026-04-28T17:19:05.587008Z","end":"2026-04-28T17:19:05.749303Z","steps":["trace[61189387] 'agreement among raft nodes before linearized reading' (duration: 162.11901ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:05.749568Z","caller":"traceutil/trace.go:171","msg":"trace[1358802168] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"162.302816ms","start":"2026-04-28T17:19:05.586769Z","end":"2026-04-28T17:19:05.749072Z","steps":["trace[1358802168] 'process raft request' (duration: 162.14147ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:05.802967Z","caller":"traceutil/trace.go:171","msg":"trace[1653893126] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"205.109555ms","start":"2026-04-28T17:19:05.597837Z","end":"2026-04-28T17:19:05.802947Z","steps":["trace[1653893126] 'process raft request' (duration: 204.899958ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:05.803035Z","caller":"traceutil/trace.go:171","msg":"trace[1759292462] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"116.771179ms","start":"2026-04-28T17:19:05.686226Z","end":"2026-04-28T17:19:05.802997Z","steps":["trace[1759292462] 'process raft request' (duration: 116.633874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:05.803233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.615868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-28T17:19:05.803322Z","caller":"traceutil/trace.go:171","msg":"trace[1557379326] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:268; }","duration":"206.710562ms","start":"2026-04-28T17:19:05.596591Z","end":"2026-04-28T17:19:05.803301Z","steps":["trace[1557379326] 'agreement among raft nodes before linearized reading' (duration: 206.574847ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:06.020122Z","caller":"traceutil/trace.go:171","msg":"trace[1244629993] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"102.199579ms","start":"2026-04-28T17:19:05.91751Z","end":"2026-04-28T17:19:06.019709Z","steps":["trace[1244629993] 'process raft request' (duration: 85.776416ms)","trace[1244629993] 'compare' (duration: 16.224816ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:28.944304Z","caller":"traceutil/trace.go:171","msg":"trace[1420502026] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"169.079331ms","start":"2026-04-28T17:19:28.775196Z","end":"2026-04-28T17:19:28.944276Z","steps":["trace[1420502026] 'read index received' (duration: 168.995949ms)","trace[1420502026] 'applied index is now lower than readState.Index' (duration: 82.282µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:28.944382Z","caller":"traceutil/trace.go:171","msg":"trace[1773445452] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"311.750506ms","start":"2026-04-28T17:19:28.632599Z","end":"2026-04-28T17:19:28.94435Z","steps":["trace[1773445452] 'process raft request' (duration: 311.5546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:28.944509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.282546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:28.944583Z","caller":"traceutil/trace.go:171","msg":"trace[1107779271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"169.42398ms","start":"2026-04-28T17:19:28.775144Z","end":"2026-04-28T17:19:28.944568Z","steps":["trace[1107779271] 'agreement among raft nodes before linearized reading' (duration: 169.285376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:28.945022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:28.632582Z","time spent":"311.8713ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:19:29.040036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.062083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:29.040128Z","caller":"traceutil/trace.go:171","msg":"trace[1992813493] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:473; }","duration":"206.207497ms","start":"2026-04-28T17:19:28.8339Z","end":"2026-04-28T17:19:29.040107Z","steps":["trace[1992813493] 'agreement among raft nodes before linearized reading' (duration: 206.023122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:29.480585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.623148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:19:29.480777Z","caller":"traceutil/trace.go:171","msg":"trace[1807555738] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"209.631605ms","start":"2026-04-28T17:19:29.27113Z","end":"2026-04-28T17:19:29.480761Z","steps":["trace[1807555738] 'process raft request' (duration: 209.578874ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:29.48129Z","caller":"traceutil/trace.go:171","msg":"trace[916338470] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"209.7967ms","start":"2026-04-28T17:19:29.271094Z","end":"2026-04-28T17:19:29.480891Z","steps":["trace[916338470] 'process raft request' (duration: 81.618236ms)","trace[916338470] 'compare' (duration: 127.430293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:30.75455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.702161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:19:30.754696Z","caller":"traceutil/trace.go:171","msg":"trace[811698711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"308.906236ms","start":"2026-04-28T17:19:30.445752Z","end":"2026-04-28T17:19:30.754659Z","steps":["trace[811698711] 'range keys from in-memory index tree' (duration: 308.573077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:30.754783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:30.445732Z","time spent":"309.031649ms","remote":"127.0.0.1:48940","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":"info","ts":"2026-04-28T17:19:31.471114Z","caller":"traceutil/trace.go:171","msg":"trace[2120558224] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"130.108165ms","start":"2026-04-28T17:19:31.340986Z","end":"2026-04-28T17:19:31.471094Z","steps":["trace[2120558224] 'process raft request' (duration: 129.94197ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486154Z","caller":"traceutil/trace.go:171","msg":"trace[1458253233] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"142.468151ms","start":"2026-04-28T17:19:31.343666Z","end":"2026-04-28T17:19:31.486135Z","steps":["trace[1458253233] 'process raft request' (duration: 141.835794ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.48626Z","caller":"traceutil/trace.go:171","msg":"trace[960711342] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"139.51698ms","start":"2026-04-28T17:19:31.346738Z","end":"2026-04-28T17:19:31.486255Z","steps":["trace[960711342] 'process raft request' (duration: 138.837702ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486325Z","caller":"traceutil/trace.go:171","msg":"trace[319871293] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"130.003421ms","start":"2026-04-28T17:19:31.356317Z","end":"2026-04-28T17:19:31.486321Z","steps":["trace[319871293] 'process raft request' (duration: 129.290222ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486441Z","caller":"traceutil/trace.go:171","msg":"trace[300629392] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"128.50056ms","start":"2026-04-28T17:19:31.357934Z","end":"2026-04-28T17:19:31.486435Z","steps":["trace[300629392] 'process raft request' (duration: 127.699718ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486545Z","caller":"traceutil/trace.go:171","msg":"trace[1207067221] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"128.283533ms","start":"2026-04-28T17:19:31.358231Z","end":"2026-04-28T17:19:31.486514Z","steps":["trace[1207067221] 'process raft request' (duration: 127.42474ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.48661Z","caller":"traceutil/trace.go:171","msg":"trace[314443703] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"101.927377ms","start":"2026-04-28T17:19:31.384677Z","end":"2026-04-28T17:19:31.486605Z","steps":["trace[314443703] 'process raft request' (duration: 101.002232ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486664Z","caller":"traceutil/trace.go:171","msg":"trace[1124642787] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"101.836194ms","start":"2026-04-28T17:19:31.384822Z","end":"2026-04-28T17:19:31.486659Z","steps":["trace[1124642787] 'process raft request' (duration: 100.886028ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.48673Z","caller":"traceutil/trace.go:171","msg":"trace[588157773] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"101.545996ms","start":"2026-04-28T17:19:31.385178Z","end":"2026-04-28T17:19:31.486724Z","steps":["trace[588157773] 'process raft request' (duration: 100.616951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:31.486774Z","caller":"traceutil/trace.go:171","msg":"trace[1458534413] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"101.889225ms","start":"2026-04-28T17:19:31.384861Z","end":"2026-04-28T17:19:31.48675Z","steps":["trace[1458534413] 'process raft request' (duration: 100.872808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:32.243886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.45615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:19:32.243982Z","caller":"traceutil/trace.go:171","msg":"trace[1516187193] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"639.976204ms","start":"2026-04-28T17:19:31.603991Z","end":"2026-04-28T17:19:32.243968Z","steps":["trace[1516187193] 'process raft request' (duration: 80.366419ms)","trace[1516187193] 'compare' (duration: 559.329567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:32.244024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.603974Z","time spent":"640.031265ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:19:32.246771Z","caller":"traceutil/trace.go:171","msg":"trace[1014558300] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"641.263778ms","start":"2026-04-28T17:19:31.605475Z","end":"2026-04-28T17:19:32.246738Z","steps":["trace[1014558300] 'process raft request' (duration: 641.049932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:32.246952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.605466Z","time spent":"641.388741ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:19:32.247205Z","caller":"traceutil/trace.go:171","msg":"trace[1109597975] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"631.743779ms","start":"2026-04-28T17:19:31.615411Z","end":"2026-04-28T17:19:32.247155Z","steps":["trace[1109597975] 'process raft request' (duration: 631.267116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:32.247331Z","caller":"traceutil/trace.go:171","msg":"trace[1692290880] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:561; }","duration":"469.813358ms","start":"2026-04-28T17:19:31.777494Z","end":"2026-04-28T17:19:32.247307Z","steps":["trace[1692290880] 'read index received' (duration: 468.927854ms)","trace[1692290880] 'applied index is now lower than readState.Index' (duration: 884.654µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:32.24735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.615397Z","time spent":"631.866832ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:19:32.24744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.936391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:32.247474Z","caller":"traceutil/trace.go:171","msg":"trace[1669661767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"470.010573ms","start":"2026-04-28T17:19:31.777454Z","end":"2026-04-28T17:19:32.247464Z","steps":["trace[1669661767] 'agreement among raft nodes before linearized reading' (duration: 469.930341ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:32.247487Z","caller":"traceutil/trace.go:171","msg":"trace[1895973285] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"628.927561ms","start":"2026-04-28T17:19:31.618548Z","end":"2026-04-28T17:19:32.247476Z","steps":["trace[1895973285] 'process raft request' (duration: 628.559381ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:32.247536Z","caller":"traceutil/trace.go:171","msg":"trace[1199382209] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"599.784048ms","start":"2026-04-28T17:19:31.647743Z","end":"2026-04-28T17:19:32.247527Z","steps":["trace[1199382209] 'process raft request' (duration: 599.433428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:32.247546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.618523Z","time spent":"629.004484ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:19:32.247595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.647712Z","time spent":"599.844459ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:19:32.247624Z","caller":"traceutil/trace.go:171","msg":"trace[1089586124] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"631.929794ms","start":"2026-04-28T17:19:31.615687Z","end":"2026-04-28T17:19:32.247617Z","steps":["trace[1089586124] 'process raft request' (duration: 631.212965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:32.247503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.777433Z","time spent":"470.064045ms","remote":"127.0.0.1:48680","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-04-28T17:19:32.247685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:31.615665Z","time spent":"631.975685ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:19:33.012587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.631255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:19:33.012672Z","caller":"traceutil/trace.go:171","msg":"trace[118407333] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"154.598989ms","start":"2026-04-28T17:19:32.858061Z","end":"2026-04-28T17:19:33.01266Z","steps":["trace[118407333] 'process raft request' (duration: 11.83265ms)","trace[118407333] 'compare' (duration: 142.537673ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:33.019198Z","caller":"traceutil/trace.go:171","msg":"trace[463440520] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"146.379355ms","start":"2026-04-28T17:19:32.872793Z","end":"2026-04-28T17:19:33.019172Z","steps":["trace[463440520] 'process raft request' (duration: 146.19769ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:33.111162Z","caller":"traceutil/trace.go:171","msg":"trace[556000487] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"148.121705ms","start":"2026-04-28T17:19:32.962998Z","end":"2026-04-28T17:19:33.11112Z","steps":["trace[556000487] 'read index received' (duration: 56.601338ms)","trace[556000487] 'applied index is now lower than readState.Index' (duration: 91.516977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:33.111405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.383972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T17:19:33.111446Z","caller":"traceutil/trace.go:171","msg":"trace[1340101603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"148.480714ms","start":"2026-04-28T17:19:32.962955Z","end":"2026-04-28T17:19:33.111436Z","steps":["trace[1340101603] 'agreement among raft nodes before linearized reading' (duration: 148.29672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:33.111585Z","caller":"traceutil/trace.go:171","msg":"trace[1028191978] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"158.333331ms","start":"2026-04-28T17:19:32.953111Z","end":"2026-04-28T17:19:33.111444Z","steps":["trace[1028191978] 'process raft request' (duration: 157.316704ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:33.548032Z","caller":"traceutil/trace.go:171","msg":"trace[155550586] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"151.711163ms","start":"2026-04-28T17:19:33.396286Z","end":"2026-04-28T17:19:33.547997Z","steps":["trace[155550586] 'process raft request' (duration: 151.553379ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:33.599059Z","caller":"traceutil/trace.go:171","msg":"trace[1504446689] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"166.09113ms","start":"2026-04-28T17:19:33.432915Z","end":"2026-04-28T17:19:33.599006Z","steps":["trace[1504446689] 'process raft request' (duration: 165.706629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:33.852319Z","caller":"traceutil/trace.go:171","msg":"trace[1781746554] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"168.669879ms","start":"2026-04-28T17:19:33.683602Z","end":"2026-04-28T17:19:33.852271Z","steps":["trace[1781746554] 'process raft request' (duration: 124.384539ms)","trace[1781746554] 'compare' (duration: 43.980672ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:33.852353Z","caller":"traceutil/trace.go:171","msg":"trace[1820449525] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"134.366148ms","start":"2026-04-28T17:19:33.717962Z","end":"2026-04-28T17:19:33.852328Z","steps":["trace[1820449525] 'read index received' (duration: 89.966006ms)","trace[1820449525] 'applied index is now lower than readState.Index' (duration: 44.399202ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:33.85246Z","caller":"traceutil/trace.go:171","msg":"trace[1862465370] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"154.825047ms","start":"2026-04-28T17:19:33.697594Z","end":"2026-04-28T17:19:33.852419Z","steps":["trace[1862465370] 'process raft request' (duration: 154.58318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:33.852605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.616135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-28T17:19:33.852656Z","caller":"traceutil/trace.go:171","msg":"trace[374883947] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:593; }","duration":"134.714597ms","start":"2026-04-28T17:19:33.717926Z","end":"2026-04-28T17:19:33.852641Z","steps":["trace[374883947] 'agreement among raft nodes before linearized reading' (duration: 134.529813ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:35.21819Z","caller":"traceutil/trace.go:171","msg":"trace[1365446982] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"321.898825ms","start":"2026-04-28T17:19:34.896276Z","end":"2026-04-28T17:19:35.218175Z","steps":["trace[1365446982] 'process raft request' (duration: 321.808633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:35.218286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:34.896252Z","time spent":"321.990297ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:19:35.2323Z","caller":"traceutil/trace.go:171","msg":"trace[104214355] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"133.309961ms","start":"2026-04-28T17:19:35.098967Z","end":"2026-04-28T17:19:35.232277Z","steps":["trace[104214355] 'process raft request' (duration: 124.934569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:36.611183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.628946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:19:36.611406Z","caller":"traceutil/trace.go:171","msg":"trace[1095104293] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"314.216999ms","start":"2026-04-28T17:19:36.297155Z","end":"2026-04-28T17:19:36.611372Z","steps":["trace[1095104293] 'process raft request' (duration: 158.334376ms)","trace[1095104293] 'compare' (duration: 155.524613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:36.611562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:36.297103Z","time spent":"314.374673ms","remote":"127.0.0.1:48940","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-04-28T17:19:36.612366Z","caller":"traceutil/trace.go:171","msg":"trace[43121206] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"285.303169ms","start":"2026-04-28T17:19:36.327036Z","end":"2026-04-28T17:19:36.612339Z","steps":["trace[43121206] 'process raft request' (duration: 284.421936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:36.61252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.224835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-28T17:19:36.612362Z","caller":"traceutil/trace.go:171","msg":"trace[520820246] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:658; }","duration":"117.054471ms","start":"2026-04-28T17:19:36.495281Z","end":"2026-04-28T17:19:36.612336Z","steps":["trace[520820246] 'read index received' (duration: 46.256978ms)","trace[520820246] 'applied index is now lower than readState.Index' (duration: 70.796613ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:36.612568Z","caller":"traceutil/trace.go:171","msg":"trace[341909455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"117.321158ms","start":"2026-04-28T17:19:36.495236Z","end":"2026-04-28T17:19:36.612557Z","steps":["trace[341909455] 'agreement among raft nodes before linearized reading' (duration: 117.164563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:36.612769Z","caller":"traceutil/trace.go:171","msg":"trace[356260395] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"234.190973ms","start":"2026-04-28T17:19:36.378568Z","end":"2026-04-28T17:19:36.612759Z","steps":["trace[356260395] 'process raft request' (duration: 233.728521ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:36.613035Z","caller":"traceutil/trace.go:171","msg":"trace[1091327056] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"279.450333ms","start":"2026-04-28T17:19:36.333564Z","end":"2026-04-28T17:19:36.613015Z","steps":["trace[1091327056] 'process raft request' (duration: 278.683864ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:36.854054Z","caller":"traceutil/trace.go:171","msg":"trace[2042835656] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"135.356702ms","start":"2026-04-28T17:19:36.718673Z","end":"2026-04-28T17:19:36.85403Z","steps":["trace[2042835656] 'read index received' (duration: 109.678227ms)","trace[2042835656] 'applied index is now lower than readState.Index' (duration: 25.677535ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:36.854138Z","caller":"traceutil/trace.go:171","msg":"trace[1436097855] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"165.31203ms","start":"2026-04-28T17:19:36.68879Z","end":"2026-04-28T17:19:36.854102Z","steps":["trace[1436097855] 'process raft request' (duration: 139.531552ms)","trace[1436097855] 'compare' (duration: 25.47715ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:36.854185Z","caller":"traceutil/trace.go:171","msg":"trace[2091073906] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"153.375916ms","start":"2026-04-28T17:19:36.700795Z","end":"2026-04-28T17:19:36.854171Z","steps":["trace[2091073906] 'process raft request' (duration: 153.176921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:36.85448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.795254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:36.854542Z","caller":"traceutil/trace.go:171","msg":"trace[506733002] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:643; }","duration":"135.889037ms","start":"2026-04-28T17:19:36.718636Z","end":"2026-04-28T17:19:36.854525Z","steps":["trace[506733002] 'agreement among raft nodes before linearized reading' (duration: 135.806515ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:41.913493Z","caller":"traceutil/trace.go:171","msg":"trace[595758086] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"175.243104ms","start":"2026-04-28T17:19:41.73822Z","end":"2026-04-28T17:19:41.913463Z","steps":["trace[595758086] 'process raft request' (duration: 175.072189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.913655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.451901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.913496Z","caller":"traceutil/trace.go:171","msg":"trace[2051735411] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:710; }","duration":"168.291297ms","start":"2026-04-28T17:19:41.745168Z","end":"2026-04-28T17:19:41.913459Z","steps":["trace[2051735411] 'read index received' (duration: 168.280256ms)","trace[2051735411] 'applied index is now lower than readState.Index' (duration: 9.201µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:41.913718Z","caller":"traceutil/trace.go:171","msg":"trace[1579881858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:686; }","duration":"168.581675ms","start":"2026-04-28T17:19:41.745122Z","end":"2026-04-28T17:19:41.913703Z","steps":["trace[1579881858] 'agreement among raft nodes before linearized reading' (duration: 168.44101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.913785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.597015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.913854Z","caller":"traceutil/trace.go:171","msg":"trace[1616108575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:686; }","duration":"168.697398ms","start":"2026-04-28T17:19:41.745121Z","end":"2026-04-28T17:19:41.913818Z","steps":["trace[1616108575] 'agreement among raft nodes before linearized reading' (duration: 168.604795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.916591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.454788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.916697Z","caller":"traceutil/trace.go:171","msg":"trace[813275525] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:686; }","duration":"153.585692ms","start":"2026-04-28T17:19:41.763093Z","end":"2026-04-28T17:19:41.916679Z","steps":["trace[813275525] 'agreement among raft nodes before linearized reading' (duration: 153.443098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.917008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.643542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.917078Z","caller":"traceutil/trace.go:171","msg":"trace[1417092977] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:686; }","duration":"131.737185ms","start":"2026-04-28T17:19:41.785327Z","end":"2026-04-28T17:19:41.917064Z","steps":["trace[1417092977] 'agreement among raft nodes before linearized reading' (duration: 131.637562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.919051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.729684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.919099Z","caller":"traceutil/trace.go:171","msg":"trace[1918733068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"133.835607ms","start":"2026-04-28T17:19:41.785248Z","end":"2026-04-28T17:19:41.919083Z","steps":["trace[1918733068] 'agreement among raft nodes before linearized reading' (duration: 133.773986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.919244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.9299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.919285Z","caller":"traceutil/trace.go:171","msg":"trace[521782740] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:686; }","duration":"133.979112ms","start":"2026-04-28T17:19:41.785291Z","end":"2026-04-28T17:19:41.91927Z","steps":["trace[521782740] 'agreement among raft nodes before linearized reading' (duration: 133.928991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.919364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.074674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.919403Z","caller":"traceutil/trace.go:171","msg":"trace[226747808] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:686; }","duration":"134.141566ms","start":"2026-04-28T17:19:41.785251Z","end":"2026-04-28T17:19:41.919392Z","steps":["trace[226747808] 'agreement among raft nodes before linearized reading' (duration: 134.089315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.919473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.26691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.919507Z","caller":"traceutil/trace.go:171","msg":"trace[1253505908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:686; }","duration":"156.328612ms","start":"2026-04-28T17:19:41.763168Z","end":"2026-04-28T17:19:41.919496Z","steps":["trace[1253505908] 'agreement among raft nodes before linearized reading' (duration: 156.285381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:41.919628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.435275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:41.919664Z","caller":"traceutil/trace.go:171","msg":"trace[476359617] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:686; }","duration":"156.485136ms","start":"2026-04-28T17:19:41.763171Z","end":"2026-04-28T17:19:41.919656Z","steps":["trace[476359617] 'agreement among raft nodes before linearized reading' (duration: 156.431165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:42.31022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.162699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:19:42.310332Z","caller":"traceutil/trace.go:171","msg":"trace[1969802896] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"369.719696ms","start":"2026-04-28T17:19:41.940588Z","end":"2026-04-28T17:19:42.310308Z","steps":["trace[1969802896] 'process raft request' (duration: 264.380962ms)","trace[1969802896] 'compare' (duration: 104.917313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:19:42.310411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:41.94057Z","time spent":"369.801108ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:19:43.701409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243891846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:19:43.701444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.320402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:19:43.701522Z","caller":"traceutil/trace.go:171","msg":"trace[1668035457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"779.447025ms","start":"2026-04-28T17:19:42.922046Z","end":"2026-04-28T17:19:43.701493Z","steps":["trace[1668035457] 'range keys from in-memory index tree' (duration: 779.110575ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:19:43.70147Z","caller":"traceutil/trace.go:171","msg":"trace[1919923780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"1.244008769s","start":"2026-04-28T17:19:42.457448Z","end":"2026-04-28T17:19:43.701456Z","steps":["trace[1919923780] 'range keys from in-memory index tree' (duration: 1.243798684s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:43.701562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:42.92201Z","time spent":"779.543107ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T17:19:43.701581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:42.457428Z","time spent":"1.244145632s","remote":"127.0.0.1:48680","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-04-28T17:19:43.702467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072274589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T17:19:43.7025Z","caller":"traceutil/trace.go:171","msg":"trace[2087518963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"1.0723448s","start":"2026-04-28T17:19:42.630146Z","end":"2026-04-28T17:19:43.702491Z","steps":["trace[2087518963] 'range keys from in-memory index tree' (duration: 1.070765001s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:43.702521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:42.630126Z","time spent":"1.072389852s","remote":"127.0.0.1:48940","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-04-28T17:19:43.704551Z","caller":"traceutil/trace.go:171","msg":"trace[724267233] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"419.40813ms","start":"2026-04-28T17:19:43.285123Z","end":"2026-04-28T17:19:43.704531Z","steps":["trace[724267233] 'read index received' (duration: 419.222286ms)","trace[724267233] 'applied index is now lower than readState.Index' (duration: 184.784µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:19:43.704606Z","caller":"traceutil/trace.go:171","msg":"trace[375586272] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"661.203066ms","start":"2026-04-28T17:19:43.043382Z","end":"2026-04-28T17:19:43.704585Z","steps":["trace[375586272] 'process raft request' (duration: 661.009001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:43.704792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.658567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T17:19:43.704854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:43.043356Z","time spent":"661.37316ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:19:43.704878Z","caller":"traceutil/trace.go:171","msg":"trace[1359037601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"419.788281ms","start":"2026-04-28T17:19:43.285076Z","end":"2026-04-28T17:19:43.704864Z","steps":["trace[1359037601] 'agreement among raft nodes before linearized reading' (duration: 419.602666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:43.704906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.465113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T17:19:43.704928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:43.285052Z","time spent":"419.863532ms","remote":"127.0.0.1:48940","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":"info","ts":"2026-04-28T17:19:43.704964Z","caller":"traceutil/trace.go:171","msg":"trace[1155839028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"383.547056ms","start":"2026-04-28T17:19:43.321401Z","end":"2026-04-28T17:19:43.704948Z","steps":["trace[1155839028] 'agreement among raft nodes before linearized reading' (duration: 383.364431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:43.705015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:19:43.321389Z","time spent":"383.615037ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T17:19:59.923078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.76807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T17:19:59.92319Z","caller":"traceutil/trace.go:171","msg":"trace[1340863751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"128.925573ms","start":"2026-04-28T17:19:59.794245Z","end":"2026-04-28T17:19:59.923171Z","steps":["trace[1340863751] 'range keys from in-memory index tree' (duration: 128.622276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:59.923259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.627157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T17:19:59.923307Z","caller":"traceutil/trace.go:171","msg":"trace[429932910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"128.677521ms","start":"2026-04-28T17:19:59.794616Z","end":"2026-04-28T17:19:59.923293Z","steps":["trace[429932910] 'range keys from in-memory index tree' (duration: 128.533358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:19:59.923392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.691177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:19:59.923439Z","caller":"traceutil/trace.go:171","msg":"trace[809648902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"147.763083ms","start":"2026-04-28T17:19:59.775661Z","end":"2026-04-28T17:19:59.923424Z","steps":["trace[809648902] 'range keys from in-memory index tree' (duration: 147.585278ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:00.146394Z","caller":"traceutil/trace.go:171","msg":"trace[1823315718] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"218.371924ms","start":"2026-04-28T17:19:59.927997Z","end":"2026-04-28T17:20:00.146369Z","steps":["trace[1823315718] 'process raft request' (duration: 138.843805ms)","trace[1823315718] 'compare' (duration: 79.275727ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:20:00.14667Z","caller":"traceutil/trace.go:171","msg":"trace[857124196] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"218.385725ms","start":"2026-04-28T17:19:59.928272Z","end":"2026-04-28T17:20:00.146658Z","steps":["trace[857124196] 'process raft request' (duration: 218.058146ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:00.146724Z","caller":"traceutil/trace.go:171","msg":"trace[2144925441] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"218.349682ms","start":"2026-04-28T17:19:59.928348Z","end":"2026-04-28T17:20:00.146697Z","steps":["trace[2144925441] 'process raft request' (duration: 218.230842ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:06.464258Z","caller":"traceutil/trace.go:171","msg":"trace[1795280582] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"285.160452ms","start":"2026-04-28T17:20:06.17908Z","end":"2026-04-28T17:20:06.464241Z","steps":["trace[1795280582] 'process raft request' (duration: 285.13392ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:06.464285Z","caller":"traceutil/trace.go:171","msg":"trace[1325389238] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"286.966979ms","start":"2026-04-28T17:20:06.177293Z","end":"2026-04-28T17:20:06.46426Z","steps":["trace[1325389238] 'process raft request' (duration: 286.830088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:30.959021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.594436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:20:30.959157Z","caller":"traceutil/trace.go:171","msg":"trace[1976608571] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"315.33394ms","start":"2026-04-28T17:20:30.643804Z","end":"2026-04-28T17:20:30.959138Z","steps":["trace[1976608571] 'read index received' (duration: 36.432µs)","trace[1976608571] 'applied index is now lower than readState.Index' (duration: 315.296158ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:20:30.959264Z","caller":"traceutil/trace.go:171","msg":"trace[1636422316] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"613.096399ms","start":"2026-04-28T17:20:30.346152Z","end":"2026-04-28T17:20:30.959249Z","steps":["trace[1636422316] 'process raft request' (duration: 194.186022ms)","trace[1636422316] 'compare' (duration: 418.306137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:20:30.959352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:20:30.346133Z","time spent":"613.175945ms","remote":"127.0.0.1:48940","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-04-28T17:20:30.959541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.587696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:20:30.959599Z","caller":"traceutil/trace.go:171","msg":"trace[273034152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"181.678682ms","start":"2026-04-28T17:20:30.777903Z","end":"2026-04-28T17:20:30.959581Z","steps":["trace[273034152] 'agreement among raft nodes before linearized reading' (duration: 181.587146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:30.959581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.475785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:20:30.959676Z","caller":"traceutil/trace.go:171","msg":"trace[87885889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"312.608303ms","start":"2026-04-28T17:20:30.64705Z","end":"2026-04-28T17:20:30.959658Z","steps":["trace[87885889] 'agreement among raft nodes before linearized reading' (duration: 312.355716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:30.95972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:20:30.647034Z","time spent":"312.676457ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T17:20:30.959881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.067797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:20:30.959926Z","caller":"traceutil/trace.go:171","msg":"trace[1379293258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1207; }","duration":"316.136062ms","start":"2026-04-28T17:20:30.643777Z","end":"2026-04-28T17:20:30.959913Z","steps":["trace[1379293258] 'agreement among raft nodes before linearized reading' (duration: 316.008694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:30.959951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.683472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T17:20:30.959964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:20:30.643765Z","time spent":"316.188875ms","remote":"127.0.0.1:48940","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":"info","ts":"2026-04-28T17:20:30.959997Z","caller":"traceutil/trace.go:171","msg":"trace[1496405975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"249.732615ms","start":"2026-04-28T17:20:30.710251Z","end":"2026-04-28T17:20:30.959984Z","steps":["trace[1496405975] 'agreement among raft nodes before linearized reading' (duration: 249.5051ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:37.173345Z","caller":"traceutil/trace.go:171","msg":"trace[1489932648] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"139.582724ms","start":"2026-04-28T17:20:37.033738Z","end":"2026-04-28T17:20:37.17332Z","steps":["trace[1489932648] 'process raft request' (duration: 139.440315ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:37.174065Z","caller":"traceutil/trace.go:171","msg":"trace[2127897621] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1324; }","duration":"136.049819ms","start":"2026-04-28T17:20:37.038Z","end":"2026-04-28T17:20:37.17405Z","steps":["trace[2127897621] 'read index received' (duration: 136.043519ms)","trace[2127897621] 'applied index is now lower than readState.Index' (duration: 5.35µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:20:37.174226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.218818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T17:20:37.174262Z","caller":"traceutil/trace.go:171","msg":"trace[236978650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"136.289933ms","start":"2026-04-28T17:20:37.037962Z","end":"2026-04-28T17:20:37.174252Z","steps":["trace[236978650] 'agreement among raft nodes before linearized reading' (duration: 136.162716ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:37.197643Z","caller":"traceutil/trace.go:171","msg":"trace[1047813989] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"120.127336ms","start":"2026-04-28T17:20:37.077493Z","end":"2026-04-28T17:20:37.197621Z","steps":["trace[1047813989] 'process raft request' (duration: 120.009879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:37.197838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.704541ms","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-04-28T17:20:37.198278Z","caller":"traceutil/trace.go:171","msg":"trace[875451722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"159.129388ms","start":"2026-04-28T17:20:37.039112Z","end":"2026-04-28T17:20:37.198242Z","steps":["trace[875451722] 'agreement among raft nodes before linearized reading' (duration: 158.603325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:20:55.366629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.114102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:20:55.366818Z","caller":"traceutil/trace.go:171","msg":"trace[500556672] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"100.170844ms","start":"2026-04-28T17:20:55.266629Z","end":"2026-04-28T17:20:55.3668Z","steps":["trace[500556672] 'process raft request' (duration: 100.112721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:55.367018Z","caller":"traceutil/trace.go:171","msg":"trace[1218643334] linearizableReadLoop","detail":"{readStateIndex:1521; appliedIndex:1520; }","duration":"101.070192ms","start":"2026-04-28T17:20:55.265922Z","end":"2026-04-28T17:20:55.366992Z","steps":["trace[1218643334] 'read index received' (duration: 47.993µs)","trace[1218643334] 'applied index is now lower than readState.Index' (duration: 101.019379ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:20:55.367023Z","caller":"traceutil/trace.go:171","msg":"trace[2009186890] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"242.946298ms","start":"2026-04-28T17:20:55.12403Z","end":"2026-04-28T17:20:55.366976Z","steps":["trace[2009186890] 'process raft request' (duration: 70.407775ms)","trace[2009186890] 'compare' (duration: 171.810305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:20:55.367254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.319825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T17:20:55.367319Z","caller":"traceutil/trace.go:171","msg":"trace[1862414321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1474; }","duration":"101.444521ms","start":"2026-04-28T17:20:55.265857Z","end":"2026-04-28T17:20:55.367302Z","steps":["trace[1862414321] 'agreement among raft nodes before linearized reading' (duration: 101.23102ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:55.515953Z","caller":"traceutil/trace.go:171","msg":"trace[1756273499] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"140.639851ms","start":"2026-04-28T17:20:55.375291Z","end":"2026-04-28T17:20:55.515931Z","steps":["trace[1756273499] 'process raft request' (duration: 137.02415ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:55.519633Z","caller":"traceutil/trace.go:171","msg":"trace[1020150327] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"144.052883ms","start":"2026-04-28T17:20:55.375569Z","end":"2026-04-28T17:20:55.519622Z","steps":["trace[1020150327] 'process raft request' (duration: 143.969847ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:20:55.519732Z","caller":"traceutil/trace.go:171","msg":"trace[619949192] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1522; }","duration":"134.98816ms","start":"2026-04-28T17:20:55.384607Z","end":"2026-04-28T17:20:55.519595Z","steps":["trace[619949192] 'read index received' (duration: 127.674183ms)","trace[619949192] 'applied index is now lower than readState.Index' (duration: 7.313447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:20:55.519876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.250186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:20:55.519952Z","caller":"traceutil/trace.go:171","msg":"trace[1666241035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1476; }","duration":"135.356761ms","start":"2026-04-28T17:20:55.384578Z","end":"2026-04-28T17:20:55.519935Z","steps":["trace[1666241035] 'agreement among raft nodes before linearized reading' (duration: 135.174742ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:12.746443Z","caller":"traceutil/trace.go:171","msg":"trace[72258628] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"140.99486ms","start":"2026-04-28T17:21:12.605421Z","end":"2026-04-28T17:21:12.746416Z","steps":["trace[72258628] 'process raft request' (duration: 140.805071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:19.835644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.851913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b5a3399-c1bf-45d3-be4d-f032b219f066\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T17:21:19.835772Z","caller":"traceutil/trace.go:171","msg":"trace[422061457] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5b5a3399-c1bf-45d3-be4d-f032b219f066; range_end:; response_count:1; response_revision:1874; }","duration":"639.030641ms","start":"2026-04-28T17:21:19.196718Z","end":"2026-04-28T17:21:19.835749Z","steps":["trace[422061457] 'range keys from in-memory index tree' (duration: 638.593481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:19.835824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.196697Z","time spent":"639.110595ms","remote":"127.0.0.1:48862","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-5b5a3399-c1bf-45d3-be4d-f032b219f066\" "} {"level":"warn","ts":"2026-04-28T17:21:19.963012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.206ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T17:21:19.963221Z","caller":"traceutil/trace.go:171","msg":"trace[925681513] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"496.79047ms","start":"2026-04-28T17:21:19.466415Z","end":"2026-04-28T17:21:19.963205Z","steps":["trace[925681513] 'process raft request' (duration: 496.689896ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:19.963231Z","caller":"traceutil/trace.go:171","msg":"trace[615930530] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"509.428534ms","start":"2026-04-28T17:21:19.45377Z","end":"2026-04-28T17:21:19.963199Z","steps":["trace[615930530] 'read index received' (duration: 338.953632ms)","trace[615930530] 'applied index is now lower than readState.Index' (duration: 170.471742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:21:19.96339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.466397Z","time spent":"496.887455ms","remote":"127.0.0.1:48940","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-04-28T17:21:19.963579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.737227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T17:21:19.963731Z","caller":"traceutil/trace.go:171","msg":"trace[2119918221] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1875; }","duration":"509.929037ms","start":"2026-04-28T17:21:19.453724Z","end":"2026-04-28T17:21:19.963653Z","steps":["trace[2119918221] 'agreement among raft nodes before linearized reading' (duration: 509.600692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:19.963914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.453705Z","time spent":"510.167977ms","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T17:21:20.058579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.12951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:21:20.058545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.503796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b5a3399-c1bf-45d3-be4d-f032b219f066\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-04-28T17:21:20.05861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.876626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T17:21:20.058645Z","caller":"traceutil/trace.go:171","msg":"trace[1117879930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"280.194504ms","start":"2026-04-28T17:21:19.778431Z","end":"2026-04-28T17:21:20.058626Z","steps":["trace[1117879930] 'agreement among raft nodes before linearized reading' (duration: 280.10119ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.058655Z","caller":"traceutil/trace.go:171","msg":"trace[436594933] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5b5a3399-c1bf-45d3-be4d-f032b219f066; range_end:; response_count:1; response_revision:1876; }","duration":"377.657334ms","start":"2026-04-28T17:21:19.680977Z","end":"2026-04-28T17:21:20.058634Z","steps":["trace[436594933] 'agreement among raft nodes before linearized reading' (duration: 377.440144ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.058672Z","caller":"traceutil/trace.go:171","msg":"trace[944233408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1876; }","duration":"411.98782ms","start":"2026-04-28T17:21:19.646668Z","end":"2026-04-28T17:21:20.058656Z","steps":["trace[944233408] 'agreement among raft nodes before linearized reading' (duration: 411.802202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:20.058693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.68096Z","time spent":"377.724007ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5b5a3399-c1bf-45d3-be4d-f032b219f066\" "} {"level":"warn","ts":"2026-04-28T17:21:20.05872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.55799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T17:21:20.058727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.646646Z","time spent":"412.072094ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T17:21:20.058756Z","caller":"traceutil/trace.go:171","msg":"trace[948201315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1876; }","duration":"409.621163ms","start":"2026-04-28T17:21:19.649125Z","end":"2026-04-28T17:21:20.058747Z","steps":["trace[948201315] 'agreement among raft nodes before linearized reading' (duration: 409.504438ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.058541Z","caller":"traceutil/trace.go:171","msg":"trace[2059333579] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"215.638945ms","start":"2026-04-28T17:21:19.842871Z","end":"2026-04-28T17:21:20.05851Z","steps":["trace[2059333579] 'process raft request' (duration: 215.353463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:20.058788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.649117Z","time spent":"409.662945ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T17:21:20.058978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.624714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T17:21:20.059102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.131636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T17:21:20.059133Z","caller":"traceutil/trace.go:171","msg":"trace[1577448835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1876; }","duration":"411.717588ms","start":"2026-04-28T17:21:19.647288Z","end":"2026-04-28T17:21:20.059005Z","steps":["trace[1577448835] 'agreement among raft nodes before linearized reading' (duration: 411.147862ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.05915Z","caller":"traceutil/trace.go:171","msg":"trace[179257105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1876; }","duration":"580.269473ms","start":"2026-04-28T17:21:19.47887Z","end":"2026-04-28T17:21:20.059139Z","steps":["trace[179257105] 'agreement among raft nodes before linearized reading' (duration: 579.512559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:20.059181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.478853Z","time spent":"580.320574ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T17:21:20.059229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:19.647237Z","time spent":"411.945879ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T17:21:20.321657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.684113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:21:20.321785Z","caller":"traceutil/trace.go:171","msg":"trace[1292350288] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"256.986118ms","start":"2026-04-28T17:21:20.064779Z","end":"2026-04-28T17:21:20.321766Z","steps":["trace[1292350288] 'process raft request' (duration: 20.079435ms)","trace[1292350288] 'compare' (duration: 236.38024ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:21:20.395097Z","caller":"traceutil/trace.go:171","msg":"trace[1518169734] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"329.421775ms","start":"2026-04-28T17:21:20.065657Z","end":"2026-04-28T17:21:20.395079Z","steps":["trace[1518169734] 'process raft request' (duration: 329.235996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:20.395205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:20.065647Z","time spent":"329.506268ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:21:20.395511Z","caller":"traceutil/trace.go:171","msg":"trace[189484675] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"328.66322ms","start":"2026-04-28T17:21:20.06684Z","end":"2026-04-28T17:21:20.395503Z","steps":["trace[189484675] 'process raft request' (duration: 328.190789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:21:20.395557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:20.066819Z","time spent":"328.714522ms","remote":"127.0.0.1:48940","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-04-28T17:21:20.395628Z","caller":"traceutil/trace.go:171","msg":"trace[700303636] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"328.67114ms","start":"2026-04-28T17:21:20.066951Z","end":"2026-04-28T17:21:20.395622Z","steps":["trace[700303636] 'process raft request' (duration: 328.468371ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.395597Z","caller":"traceutil/trace.go:171","msg":"trace[879637174] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1952; }","duration":"246.325488ms","start":"2026-04-28T17:21:20.149248Z","end":"2026-04-28T17:21:20.395573Z","steps":["trace[879637174] 'read index received' (duration: 245.619736ms)","trace[879637174] 'applied index is now lower than readState.Index' (duration: 704.842µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:21:20.39566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:21:20.066924Z","time spent":"328.718152ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:21:20.395999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.676653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-28T17:21:20.396064Z","caller":"traceutil/trace.go:171","msg":"trace[1987572136] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1880; }","duration":"246.83977ms","start":"2026-04-28T17:21:20.149206Z","end":"2026-04-28T17:21:20.396045Z","steps":["trace[1987572136] 'agreement among raft nodes before linearized reading' (duration: 246.447892ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:20.417807Z","caller":"traceutil/trace.go:171","msg":"trace[2039964094] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"125.7607ms","start":"2026-04-28T17:21:20.292024Z","end":"2026-04-28T17:21:20.417785Z","steps":["trace[2039964094] 'process raft request' (duration: 125.633295ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:55.33598Z","caller":"traceutil/trace.go:171","msg":"trace[1435558484] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"119.719811ms","start":"2026-04-28T17:21:55.21624Z","end":"2026-04-28T17:21:55.33596Z","steps":["trace[1435558484] 'read index received' (duration: 119.561204ms)","trace[1435558484] 'applied index is now lower than readState.Index' (duration: 157.587µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:21:55.336237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.992092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T17:21:55.336273Z","caller":"traceutil/trace.go:171","msg":"trace[620634547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"120.080205ms","start":"2026-04-28T17:21:55.216183Z","end":"2026-04-28T17:21:55.336264Z","steps":["trace[620634547] 'agreement among raft nodes before linearized reading' (duration: 119.867137ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:21:55.336388Z","caller":"traceutil/trace.go:171","msg":"trace[915758095] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"186.178773ms","start":"2026-04-28T17:21:55.150182Z","end":"2026-04-28T17:21:55.33636Z","steps":["trace[915758095] 'process raft request' (duration: 185.646073ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:22:16.953201Z","caller":"traceutil/trace.go:171","msg":"trace[956138584] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2326; }","duration":"175.05317ms","start":"2026-04-28T17:22:16.77812Z","end":"2026-04-28T17:22:16.953173Z","steps":["trace[956138584] 'read index received' (duration: 174.824452ms)","trace[956138584] 'applied index is now lower than readState.Index' (duration: 227.458µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:22:16.953248Z","caller":"traceutil/trace.go:171","msg":"trace[1635925158] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"210.31401ms","start":"2026-04-28T17:22:16.742891Z","end":"2026-04-28T17:22:16.953206Z","steps":["trace[1635925158] 'process raft request' (duration: 210.107573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:16.953387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.240027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:22:16.953706Z","caller":"traceutil/trace.go:171","msg":"trace[594218458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2240; }","duration":"175.592369ms","start":"2026-04-28T17:22:16.778066Z","end":"2026-04-28T17:22:16.953659Z","steps":["trace[594218458] 'agreement among raft nodes before linearized reading' (duration: 175.226216ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:22:25.552118Z","caller":"traceutil/trace.go:171","msg":"trace[887335213] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"100.008943ms","start":"2026-04-28T17:22:25.452063Z","end":"2026-04-28T17:22:25.552072Z","steps":["trace[887335213] 'process raft request' (duration: 27.148048ms)","trace[887335213] 'compare' (duration: 72.71985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:22:25.552184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.860096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:22:25.552287Z","caller":"traceutil/trace.go:171","msg":"trace[570915921] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2293; }","duration":"132.98088ms","start":"2026-04-28T17:22:25.419255Z","end":"2026-04-28T17:22:25.552236Z","steps":["trace[570915921] 'count revisions from in-memory index tree' (duration: 132.730622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:51.389111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696381057275755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T17:22:51.515312Z","caller":"traceutil/trace.go:171","msg":"trace[988719185] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"685.061354ms","start":"2026-04-28T17:22:50.830219Z","end":"2026-04-28T17:22:51.51528Z","steps":["trace[988719185] 'process raft request' (duration: 682.809941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:51.515473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:50.8302Z","time spent":"685.189418ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:22:52.306304Z","caller":"traceutil/trace.go:171","msg":"trace[308608558] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2564; }","duration":"1.417743775s","start":"2026-04-28T17:22:50.888537Z","end":"2026-04-28T17:22:52.30628Z","steps":["trace[308608558] 'read index received' (duration: 624.50498ms)","trace[308608558] 'applied index is now lower than readState.Index' (duration: 793.237665ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:22:52.30642Z","caller":"traceutil/trace.go:171","msg":"trace[283636173] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"1.020772245s","start":"2026-04-28T17:22:51.285634Z","end":"2026-04-28T17:22:52.306406Z","steps":["trace[283636173] 'process raft request' (duration: 1.020581829s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.30689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418334423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:22:52.306947Z","caller":"traceutil/trace.go:171","msg":"trace[55725338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2472; }","duration":"1.418441058s","start":"2026-04-28T17:22:50.888494Z","end":"2026-04-28T17:22:52.306935Z","steps":["trace[55725338] 'agreement among raft nodes before linearized reading' (duration: 1.418238461s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.306973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:50.888473Z","time spent":"1.418495269s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T17:22:52.306963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.285605Z","time spent":"1.0212458s","remote":"127.0.0.1:48940","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-04-28T17:22:52.306339Z","caller":"traceutil/trace.go:171","msg":"trace[517493578] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"1.387163998s","start":"2026-04-28T17:22:50.919133Z","end":"2026-04-28T17:22:52.306297Z","steps":["trace[517493578] 'process raft request' (duration: 1.385098561s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.308495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159742006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T17:22:52.308542Z","caller":"traceutil/trace.go:171","msg":"trace[1962901629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2473; }","duration":"1.159831428s","start":"2026-04-28T17:22:51.148699Z","end":"2026-04-28T17:22:52.30853Z","steps":["trace[1962901629] 'agreement among raft nodes before linearized reading' (duration: 1.159713154s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.308574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.148677Z","time spent":"1.1598895s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T17:22:52.308147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.484983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-d8xn8\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-28T17:22:52.308982Z","caller":"traceutil/trace.go:171","msg":"trace[483349151] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-d8xn8; range_end:; response_count:1; response_revision:2473; }","duration":"687.287528ms","start":"2026-04-28T17:22:51.621598Z","end":"2026-04-28T17:22:52.308885Z","steps":["trace[483349151] 'agreement among raft nodes before linearized reading' (duration: 686.381728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.309103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.621576Z","time spent":"687.508496ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7841,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-d8xn8\" "} {"level":"warn","ts":"2026-04-28T17:22:52.308155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159180877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T17:22:52.309239Z","caller":"traceutil/trace.go:171","msg":"trace[188897364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2473; }","duration":"1.160308324s","start":"2026-04-28T17:22:51.148913Z","end":"2026-04-28T17:22:52.309221Z","steps":["trace[188897364] 'agreement among raft nodes before linearized reading' (duration: 1.159088964s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.309309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.148897Z","time spent":"1.160400057s","remote":"127.0.0.1:48940","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-04-28T17:22:52.308168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163659703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T17:22:52.309465Z","caller":"traceutil/trace.go:171","msg":"trace[682833936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2473; }","duration":"1.164922804s","start":"2026-04-28T17:22:51.144446Z","end":"2026-04-28T17:22:52.309369Z","steps":["trace[682833936] 'agreement among raft nodes before linearized reading' (duration: 1.163496447s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.308249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.068992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T17:22:52.309672Z","caller":"traceutil/trace.go:171","msg":"trace[1998976198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2473; }","duration":"922.527949ms","start":"2026-04-28T17:22:51.38713Z","end":"2026-04-28T17:22:52.309658Z","steps":["trace[1998976198] 'agreement among raft nodes before linearized reading' (duration: 921.068112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.309732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.3871Z","time spent":"922.618312ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T17:22:52.308307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.858065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:22:52.309881Z","caller":"traceutil/trace.go:171","msg":"trace[894916022] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2473; }","duration":"969.464956ms","start":"2026-04-28T17:22:51.340404Z","end":"2026-04-28T17:22:52.309869Z","steps":["trace[894916022] 'agreement among raft nodes before linearized reading' (duration: 967.862195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.309912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.340381Z","time spent":"969.521087ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-28T17:22:52.308326Z","caller":"traceutil/trace.go:171","msg":"trace[1590765998] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"658.651317ms","start":"2026-04-28T17:22:51.649656Z","end":"2026-04-28T17:22:52.308307Z","steps":["trace[1590765998] 'process raft request' (duration: 657.997295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.31011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.649634Z","time spent":"660.425174ms","remote":"127.0.0.1:48940","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-04-28T17:22:52.308347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.638283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:22:52.310274Z","caller":"traceutil/trace.go:171","msg":"trace[1519784891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2473; }","duration":"532.615847ms","start":"2026-04-28T17:22:51.777649Z","end":"2026-04-28T17:22:52.310265Z","steps":["trace[1519784891] 'agreement among raft nodes before linearized reading' (duration: 530.681395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.310305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.777624Z","time spent":"532.673339ms","remote":"127.0.0.1:48680","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-04-28T17:22:52.308386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.71836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:22:52.310544Z","caller":"traceutil/trace.go:171","msg":"trace[10449836] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2473; }","duration":"585.91324ms","start":"2026-04-28T17:22:51.724618Z","end":"2026-04-28T17:22:52.310531Z","steps":["trace[10449836] 'agreement among raft nodes before linearized reading' (duration: 583.73644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.310582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.724599Z","time spent":"585.968891ms","remote":"127.0.0.1:35964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T17:22:52.308414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.880375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-28T17:22:52.310806Z","caller":"traceutil/trace.go:171","msg":"trace[642439502] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2473; }","duration":"245.289752ms","start":"2026-04-28T17:22:52.065504Z","end":"2026-04-28T17:22:52.310794Z","steps":["trace[642439502] 'agreement among raft nodes before linearized reading' (duration: 242.858024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.308402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159633842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-28T17:22:52.311029Z","caller":"traceutil/trace.go:171","msg":"trace[330326691] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2473; }","duration":"1.162294789s","start":"2026-04-28T17:22:51.148723Z","end":"2026-04-28T17:22:52.311018Z","steps":["trace[330326691] 'agreement among raft nodes before linearized reading' (duration: 1.159483008s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:22:52.311078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.148709Z","time spent":"1.16235779s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T17:22:52.311358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:50.919104Z","time spent":"1.392198762s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:22:52.309667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:22:51.14443Z","time spent":"1.165078979s","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T17:22:56.99472Z","caller":"traceutil/trace.go:171","msg":"trace[1558239731] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2618; }","duration":"186.368695ms","start":"2026-04-28T17:22:56.808329Z","end":"2026-04-28T17:22:56.994697Z","steps":["trace[1558239731] 'read index received' (duration: 183.882846ms)","trace[1558239731] 'applied index is now lower than readState.Index' (duration: 2.485069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:22:56.994853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.50501ms","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-04-28T17:22:56.994876Z","caller":"traceutil/trace.go:171","msg":"trace[857615486] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2523; }","duration":"186.570203ms","start":"2026-04-28T17:22:56.808297Z","end":"2026-04-28T17:22:56.994868Z","steps":["trace[857615486] 'agreement among raft nodes before linearized reading' (duration: 186.473959ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:22:56.995061Z","caller":"traceutil/trace.go:171","msg":"trace[1168081866] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"284.359395ms","start":"2026-04-28T17:22:56.710694Z","end":"2026-04-28T17:22:56.995053Z","steps":["trace[1168081866] 'process raft request' (duration: 281.602796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:23:01.690728Z","caller":"traceutil/trace.go:171","msg":"trace[141758623] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"133.9381ms","start":"2026-04-28T17:23:01.556757Z","end":"2026-04-28T17:23:01.690695Z","steps":["trace[141758623] 'process raft request' (duration: 133.707603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:02.265013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.621512ms","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-04-28T17:23:02.265372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.204913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:23:02.265403Z","caller":"traceutil/trace.go:171","msg":"trace[967085741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2581; }","duration":"448.862319ms","start":"2026-04-28T17:23:01.816272Z","end":"2026-04-28T17:23:02.265134Z","steps":["trace[967085741] 'count revisions from in-memory index tree' (duration: 448.506728ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:23:02.2655Z","caller":"traceutil/trace.go:171","msg":"trace[1209757796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2581; }","duration":"486.475962ms","start":"2026-04-28T17:23:01.778993Z","end":"2026-04-28T17:23:02.265469Z","steps":["trace[1209757796] 'range keys from in-memory index tree' (duration: 486.044138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:02.265582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:23:01.778946Z","time spent":"486.622306ms","remote":"127.0.0.1:48680","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-04-28T17:23:02.265808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:23:01.816243Z","time spent":"449.251621ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-28T17:23:26.783301Z","caller":"traceutil/trace.go:171","msg":"trace[1966334227] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"173.501664ms","start":"2026-04-28T17:23:26.609775Z","end":"2026-04-28T17:23:26.783277Z","steps":["trace[1966334227] 'process raft request' (duration: 118.886098ms)","trace[1966334227] 'compare' (duration: 54.522414ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:23:37.515332Z","caller":"traceutil/trace.go:171","msg":"trace[1392171288] linearizableReadLoop","detail":"{readStateIndex:2883; appliedIndex:2882; }","duration":"146.56199ms","start":"2026-04-28T17:23:37.368742Z","end":"2026-04-28T17:23:37.515304Z","steps":["trace[1392171288] 'read index received' (duration: 146.368604ms)","trace[1392171288] 'applied index is now lower than readState.Index' (duration: 192.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:23:37.515406Z","caller":"traceutil/trace.go:171","msg":"trace[644389088] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"154.388229ms","start":"2026-04-28T17:23:37.360978Z","end":"2026-04-28T17:23:37.515367Z","steps":["trace[644389088] 'process raft request' (duration: 154.183983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:37.515594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.830688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T17:23:37.515645Z","caller":"traceutil/trace.go:171","msg":"trace[1547428380] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2778; }","duration":"146.936391ms","start":"2026-04-28T17:23:37.368694Z","end":"2026-04-28T17:23:37.51563Z","steps":["trace[1547428380] 'agreement among raft nodes before linearized reading' (duration: 146.768366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:37.515791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.623309ms","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-04-28T17:23:37.515853Z","caller":"traceutil/trace.go:171","msg":"trace[1742514816] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2778; }","duration":"139.710461ms","start":"2026-04-28T17:23:37.376125Z","end":"2026-04-28T17:23:37.515835Z","steps":["trace[1742514816] 'agreement among raft nodes before linearized reading' (duration: 139.500955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:38.179605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.504297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:23:38.179699Z","caller":"traceutil/trace.go:171","msg":"trace[1576879320] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"403.096499ms","start":"2026-04-28T17:23:37.776587Z","end":"2026-04-28T17:23:38.179683Z","steps":["trace[1576879320] 'read index received' (duration: 112.293µs)","trace[1576879320] 'applied index is now lower than readState.Index' (duration: 402.983066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:23:38.179781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.219113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:23:38.179824Z","caller":"traceutil/trace.go:171","msg":"trace[1423021221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2780; }","duration":"403.324116ms","start":"2026-04-28T17:23:37.776487Z","end":"2026-04-28T17:23:38.179812Z","steps":["trace[1423021221] 'agreement among raft nodes before linearized reading' (duration: 403.239353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:38.179864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:23:37.776456Z","time spent":"403.397648ms","remote":"127.0.0.1:48680","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-04-28T17:23:38.1801Z","caller":"traceutil/trace.go:171","msg":"trace[662125686] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"579.340695ms","start":"2026-04-28T17:23:37.600743Z","end":"2026-04-28T17:23:38.180084Z","steps":["trace[662125686] 'process raft request' (duration: 144.265921ms)","trace[662125686] 'compare' (duration: 434.168137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:23:38.180172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:23:37.600723Z","time spent":"579.415276ms","remote":"127.0.0.1:48940","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-04-28T17:23:38.181053Z","caller":"traceutil/trace.go:171","msg":"trace[908210278] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"343.917944ms","start":"2026-04-28T17:23:37.837106Z","end":"2026-04-28T17:23:38.181024Z","steps":["trace[908210278] 'process raft request' (duration: 343.673366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:23:38.181334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:23:37.83707Z","time spent":"344.10831ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:24:04.939029Z","caller":"traceutil/trace.go:171","msg":"trace[1503714757] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"137.436311ms","start":"2026-04-28T17:24:04.801567Z","end":"2026-04-28T17:24:04.939004Z","steps":["trace[1503714757] 'process raft request' (duration: 137.38915ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:24:04.939457Z","caller":"traceutil/trace.go:171","msg":"trace[514936493] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"137.924635ms","start":"2026-04-28T17:24:04.801492Z","end":"2026-04-28T17:24:04.939417Z","steps":["trace[514936493] 'process raft request' (duration: 137.39305ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:24:04.939562Z","caller":"traceutil/trace.go:171","msg":"trace[829271082] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"138.111251ms","start":"2026-04-28T17:24:04.801408Z","end":"2026-04-28T17:24:04.939519Z","steps":["trace[829271082] 'process raft request' (duration: 76.616416ms)","trace[829271082] 'compare' (duration: 60.605249ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:24:33.109046Z","caller":"traceutil/trace.go:171","msg":"trace[1011411676] transaction","detail":"{read_only:false; response_revision:3070; number_of_response:1; }","duration":"109.367384ms","start":"2026-04-28T17:24:32.999657Z","end":"2026-04-28T17:24:33.109024Z","steps":["trace[1011411676] 'process raft request' (duration: 109.054049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:41.471002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.286555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:24:41.471597Z","caller":"traceutil/trace.go:171","msg":"trace[1055811510] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"226.341833ms","start":"2026-04-28T17:24:41.245165Z","end":"2026-04-28T17:24:41.471507Z","steps":["trace[1055811510] 'process raft request' (duration: 107.475889ms)","trace[1055811510] 'compare' (duration: 117.944399ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:24:41.471761Z","caller":"traceutil/trace.go:171","msg":"trace[1769298779] transaction","detail":"{read_only:false; response_revision:3117; number_of_response:1; }","duration":"225.910835ms","start":"2026-04-28T17:24:41.245835Z","end":"2026-04-28T17:24:41.471746Z","steps":["trace[1769298779] 'process raft request' (duration: 225.788623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:24:41.471777Z","caller":"traceutil/trace.go:171","msg":"trace[1223907572] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"225.949175ms","start":"2026-04-28T17:24:41.24574Z","end":"2026-04-28T17:24:41.471689Z","steps":["trace[1223907572] 'process raft request' (duration: 225.66425ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:24:41.471692Z","caller":"traceutil/trace.go:171","msg":"trace[1527771568] linearizableReadLoop","detail":"{readStateIndex:3237; appliedIndex:3233; }","duration":"160.488405ms","start":"2026-04-28T17:24:41.311187Z","end":"2026-04-28T17:24:41.471675Z","steps":["trace[1527771568] 'read index received' (duration: 41.477988ms)","trace[1527771568] 'applied index is now lower than readState.Index' (duration: 119.009647ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:24:41.471906Z","caller":"traceutil/trace.go:171","msg":"trace[1586623418] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"226.019606ms","start":"2026-04-28T17:24:41.245872Z","end":"2026-04-28T17:24:41.471892Z","steps":["trace[1586623418] 'process raft request' (duration: 225.773902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:41.472064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.83601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:24:41.472332Z","caller":"traceutil/trace.go:171","msg":"trace[1888644404] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3118; }","duration":"161.177986ms","start":"2026-04-28T17:24:41.311139Z","end":"2026-04-28T17:24:41.472317Z","steps":["trace[1888644404] 'agreement among raft nodes before linearized reading' (duration: 160.80958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:42.892941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.762656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:24:42.89312Z","caller":"traceutil/trace.go:171","msg":"trace[2065025194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3119; }","duration":"115.010811ms","start":"2026-04-28T17:24:42.778086Z","end":"2026-04-28T17:24:42.893097Z","steps":["trace[2065025194] 'range keys from in-memory index tree' (duration: 114.637634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:42.893475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.836532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:24:42.893569Z","caller":"traceutil/trace.go:171","msg":"trace[1010764561] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3119; }","duration":"301.968344ms","start":"2026-04-28T17:24:42.591576Z","end":"2026-04-28T17:24:42.893544Z","steps":["trace[1010764561] 'range keys from in-memory index tree' (duration: 301.758811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:42.893628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:42.591558Z","time spent":"302.054296ms","remote":"127.0.0.1:49160","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-04-28T17:24:43.093393Z","caller":"traceutil/trace.go:171","msg":"trace[668595257] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"150.804047ms","start":"2026-04-28T17:24:42.942564Z","end":"2026-04-28T17:24:43.093368Z","steps":["trace[668595257] 'process raft request' (duration: 79.142604ms)","trace[668595257] 'compare' (duration: 71.404409ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:11.430149Z","caller":"traceutil/trace.go:171","msg":"trace[632855812] transaction","detail":"{read_only:false; response_revision:3268; number_of_response:1; }","duration":"160.169967ms","start":"2026-04-28T17:25:11.269932Z","end":"2026-04-28T17:25:11.430101Z","steps":["trace[632855812] 'process raft request' (duration: 84.787636ms)","trace[632855812] 'compare' (duration: 75.132585ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:11.588599Z","caller":"traceutil/trace.go:171","msg":"trace[178556075] transaction","detail":"{read_only:false; response_revision:3269; number_of_response:1; }","duration":"126.598311ms","start":"2026-04-28T17:25:11.461974Z","end":"2026-04-28T17:25:11.588573Z","steps":["trace[178556075] 'process raft request' (duration: 79.942974ms)","trace[178556075] 'compare' (duration: 46.395432ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:15.368754Z","caller":"traceutil/trace.go:171","msg":"trace[1378408845] transaction","detail":"{read_only:false; response_revision:3287; number_of_response:1; }","duration":"126.636385ms","start":"2026-04-28T17:25:15.242097Z","end":"2026-04-28T17:25:15.368734Z","steps":["trace[1378408845] 'process raft request' (duration: 33.671994ms)","trace[1378408845] 'compare' (duration: 92.88659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:29.103595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.971534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:25:29.103706Z","caller":"traceutil/trace.go:171","msg":"trace[92969349] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:3355; }","duration":"110.147528ms","start":"2026-04-28T17:25:28.993538Z","end":"2026-04-28T17:25:29.103685Z","steps":["trace[92969349] 'count revisions from in-memory index tree' (duration: 109.895942ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:36.157187Z","caller":"traceutil/trace.go:171","msg":"trace[1405108144] linearizableReadLoop","detail":"{readStateIndex:3522; appliedIndex:3521; }","duration":"155.02462ms","start":"2026-04-28T17:25:36.002133Z","end":"2026-04-28T17:25:36.157158Z","steps":["trace[1405108144] 'read index received' (duration: 136.312845ms)","trace[1405108144] 'applied index is now lower than readState.Index' (duration: 18.710825ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:36.157282Z","caller":"traceutil/trace.go:171","msg":"trace[565679459] transaction","detail":"{read_only:false; response_revision:3392; number_of_response:1; }","duration":"160.436969ms","start":"2026-04-28T17:25:35.996797Z","end":"2026-04-28T17:25:36.157234Z","steps":["trace[565679459] 'process raft request' (duration: 141.631762ms)","trace[565679459] 'compare' (duration: 18.592753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:36.157753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.596203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T17:25:36.157852Z","caller":"traceutil/trace.go:171","msg":"trace[1437346583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3392; }","duration":"155.723156ms","start":"2026-04-28T17:25:36.002106Z","end":"2026-04-28T17:25:36.15783Z","steps":["trace[1437346583] 'agreement among raft nodes before linearized reading' (duration: 155.50347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:36.157986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.45855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T17:25:36.158048Z","caller":"traceutil/trace.go:171","msg":"trace[368810285] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3392; }","duration":"152.556143ms","start":"2026-04-28T17:25:36.005472Z","end":"2026-04-28T17:25:36.158028Z","steps":["trace[368810285] 'agreement among raft nodes before linearized reading' (duration: 152.357029ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:40.400249Z","caller":"traceutil/trace.go:171","msg":"trace[249059307] linearizableReadLoop","detail":"{readStateIndex:3546; appliedIndex:3544; }","duration":"179.52042ms","start":"2026-04-28T17:25:40.220706Z","end":"2026-04-28T17:25:40.400227Z","steps":["trace[249059307] 'read index received' (duration: 111.361138ms)","trace[249059307] 'applied index is now lower than readState.Index' (duration: 68.158242ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:40.400317Z","caller":"traceutil/trace.go:171","msg":"trace[1821791686] transaction","detail":"{read_only:false; response_revision:3414; number_of_response:1; }","duration":"183.366348ms","start":"2026-04-28T17:25:40.216917Z","end":"2026-04-28T17:25:40.400283Z","steps":["trace[1821791686] 'process raft request' (duration: 114.973971ms)","trace[1821791686] 'compare' (duration: 68.137932ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:40.400345Z","caller":"traceutil/trace.go:171","msg":"trace[1355266773] transaction","detail":"{read_only:false; response_revision:3415; number_of_response:1; }","duration":"183.341237ms","start":"2026-04-28T17:25:40.216965Z","end":"2026-04-28T17:25:40.400307Z","steps":["trace[1355266773] 'process raft request' (duration: 183.216735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:40.400385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.649342ms","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-04-28T17:25:40.400425Z","caller":"traceutil/trace.go:171","msg":"trace[1883359607] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3415; }","duration":"179.731305ms","start":"2026-04-28T17:25:40.220683Z","end":"2026-04-28T17:25:40.400414Z","steps":["trace[1883359607] 'agreement among raft nodes before linearized reading' (duration: 179.634953ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:40.606406Z","caller":"traceutil/trace.go:171","msg":"trace[979543064] transaction","detail":"{read_only:false; response_revision:3416; number_of_response:1; }","duration":"131.997618ms","start":"2026-04-28T17:25:40.474391Z","end":"2026-04-28T17:25:40.606388Z","steps":["trace[979543064] 'process raft request' (duration: 131.878994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:42.430318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.298049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7508"} {"level":"info","ts":"2026-04-28T17:25:42.4304Z","caller":"traceutil/trace.go:171","msg":"trace[408272224] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3433; }","duration":"112.425852ms","start":"2026-04-28T17:25:42.317956Z","end":"2026-04-28T17:25:42.430382Z","steps":["trace[408272224] 'range keys from in-memory index tree' (duration: 112.097355ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:44.046931Z","caller":"traceutil/trace.go:171","msg":"trace[686022499] transaction","detail":"{read_only:false; response_revision:3449; number_of_response:1; }","duration":"124.439756ms","start":"2026-04-28T17:25:43.922463Z","end":"2026-04-28T17:25:44.046903Z","steps":["trace[686022499] 'process raft request' (duration: 120.597306ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:44.046985Z","caller":"traceutil/trace.go:171","msg":"trace[1428039263] transaction","detail":"{read_only:false; response_revision:3451; number_of_response:1; }","duration":"106.655031ms","start":"2026-04-28T17:25:43.94031Z","end":"2026-04-28T17:25:44.046965Z","steps":["trace[1428039263] 'process raft request' (duration: 106.6109ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:44.047157Z","caller":"traceutil/trace.go:171","msg":"trace[426267117] transaction","detail":"{read_only:false; response_revision:3450; number_of_response:1; }","duration":"118.286278ms","start":"2026-04-28T17:25:43.928842Z","end":"2026-04-28T17:25:44.047128Z","steps":["trace[426267117] 'process raft request' (duration: 117.977332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:44.047384Z","caller":"traceutil/trace.go:171","msg":"trace[989089929] linearizableReadLoop","detail":"{readStateIndex:3583; appliedIndex:3582; }","duration":"119.500304ms","start":"2026-04-28T17:25:43.927866Z","end":"2026-04-28T17:25:44.047366Z","steps":["trace[989089929] 'read index received' (duration: 11.389933ms)","trace[989089929] 'applied index is now lower than readState.Index' (duration: 108.108181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:44.047609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.727849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-28T17:25:44.047665Z","caller":"traceutil/trace.go:171","msg":"trace[786111117] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3451; }","duration":"119.827901ms","start":"2026-04-28T17:25:43.927823Z","end":"2026-04-28T17:25:44.047651Z","steps":["trace[786111117] 'agreement among raft nodes before linearized reading' (duration: 119.645047ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:44.634659Z","caller":"traceutil/trace.go:171","msg":"trace[321768745] transaction","detail":"{read_only:false; response_revision:3473; number_of_response:1; }","duration":"161.994138ms","start":"2026-04-28T17:25:44.472621Z","end":"2026-04-28T17:25:44.634615Z","steps":["trace[321768745] 'process raft request' (duration: 79.982058ms)","trace[321768745] 'compare' (duration: 81.920018ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:45.410636Z","caller":"traceutil/trace.go:171","msg":"trace[1686646596] transaction","detail":"{read_only:false; response_revision:3476; number_of_response:1; }","duration":"165.701912ms","start":"2026-04-28T17:25:45.244899Z","end":"2026-04-28T17:25:45.410601Z","steps":["trace[1686646596] 'process raft request' (duration: 80.619526ms)","trace[1686646596] 'compare' (duration: 84.75576ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:45.41088Z","caller":"traceutil/trace.go:171","msg":"trace[276305041] transaction","detail":"{read_only:false; response_revision:3477; number_of_response:1; }","duration":"140.218369ms","start":"2026-04-28T17:25:45.270635Z","end":"2026-04-28T17:25:45.410854Z","steps":["trace[276305041] 'process raft request' (duration: 139.857031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:45.410955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.796541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:45.411146Z","caller":"traceutil/trace.go:171","msg":"trace[1581567404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev; range_end:; response_count:0; response_revision:3479; }","duration":"140.189809ms","start":"2026-04-28T17:25:45.270933Z","end":"2026-04-28T17:25:45.411123Z","steps":["trace[1581567404] 'agreement among raft nodes before linearized reading' (duration: 139.77388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:45.411237Z","caller":"traceutil/trace.go:171","msg":"trace[467809636] transaction","detail":"{read_only:false; response_revision:3478; number_of_response:1; }","duration":"140.495395ms","start":"2026-04-28T17:25:45.270716Z","end":"2026-04-28T17:25:45.411211Z","steps":["trace[467809636] 'process raft request' (duration: 139.84416ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:45.411274Z","caller":"traceutil/trace.go:171","msg":"trace[169349993] transaction","detail":"{read_only:false; response_revision:3479; number_of_response:1; }","duration":"120.211619ms","start":"2026-04-28T17:25:45.291043Z","end":"2026-04-28T17:25:45.411255Z","steps":["trace[169349993] 'process raft request' (duration: 119.556194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:45.410672Z","caller":"traceutil/trace.go:171","msg":"trace[1526286975] linearizableReadLoop","detail":"{readStateIndex:3613; appliedIndex:3610; }","duration":"139.671768ms","start":"2026-04-28T17:25:45.270984Z","end":"2026-04-28T17:25:45.410656Z","steps":["trace[1526286975] 'read index received' (duration: 54.520719ms)","trace[1526286975] 'applied index is now lower than readState.Index' (duration: 85.150149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:45.411552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.11287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:45.411597Z","caller":"traceutil/trace.go:171","msg":"trace[1994151854] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:3479; }","duration":"126.202261ms","start":"2026-04-28T17:25:45.285381Z","end":"2026-04-28T17:25:45.411583Z","steps":["trace[1994151854] 'agreement among raft nodes before linearized reading' (duration: 126.12401ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:45.578742Z","caller":"traceutil/trace.go:171","msg":"trace[1562965850] transaction","detail":"{read_only:false; response_revision:3480; number_of_response:1; }","duration":"161.544847ms","start":"2026-04-28T17:25:45.417146Z","end":"2026-04-28T17:25:45.578691Z","steps":["trace[1562965850] 'process raft request' (duration: 99.118116ms)","trace[1562965850] 'compare' (duration: 62.109055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:46.640256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.201079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7508"} {"level":"info","ts":"2026-04-28T17:25:46.640353Z","caller":"traceutil/trace.go:171","msg":"trace[118587052] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3528; }","duration":"111.356892ms","start":"2026-04-28T17:25:46.52897Z","end":"2026-04-28T17:25:46.640327Z","steps":["trace[118587052] 'range keys from in-memory index tree' (duration: 111.024545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:09.350824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T17:26:09.350919Z","caller":"traceutil/trace.go:171","msg":"trace[1683222791] linearizableReadLoop","detail":"{readStateIndex:3799; appliedIndex:3798; }","duration":"114.417855ms","start":"2026-04-28T17:26:09.236486Z","end":"2026-04-28T17:26:09.350904Z","steps":["trace[1683222791] 'read index received' (duration: 2.454102ms)","trace[1683222791] 'applied index is now lower than readState.Index' (duration: 111.962493ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:09.351075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.594208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T17:26:09.351103Z","caller":"traceutil/trace.go:171","msg":"trace[739190567] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3659; }","duration":"114.6563ms","start":"2026-04-28T17:26:09.236437Z","end":"2026-04-28T17:26:09.351094Z","steps":["trace[739190567] 'agreement among raft nodes before linearized reading' (duration: 114.538127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:10.101964Z","caller":"traceutil/trace.go:171","msg":"trace[1194431759] linearizableReadLoop","detail":"{readStateIndex:3806; appliedIndex:3804; }","duration":"167.938287ms","start":"2026-04-28T17:26:09.934001Z","end":"2026-04-28T17:26:10.10194Z","steps":["trace[1194431759] 'read index received' (duration: 131.20697ms)","trace[1194431759] 'applied index is now lower than readState.Index' (duration: 36.730147ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:10.102088Z","caller":"traceutil/trace.go:171","msg":"trace[23712666] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"172.64626ms","start":"2026-04-28T17:26:09.929431Z","end":"2026-04-28T17:26:10.102077Z","steps":["trace[23712666] 'process raft request' (duration: 172.453104ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:10.102277Z","caller":"traceutil/trace.go:171","msg":"trace[1760605374] transaction","detail":"{read_only:false; response_revision:3665; number_of_response:1; }","duration":"176.543893ms","start":"2026-04-28T17:26:09.9257Z","end":"2026-04-28T17:26:10.102244Z","steps":["trace[1760605374] 'process raft request' (duration: 139.49338ms)","trace[1760605374] 'compare' (duration: 36.549901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:10.102488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.432537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-04-28T17:26:10.10271Z","caller":"traceutil/trace.go:171","msg":"trace[1501440552] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3666; }","duration":"168.676543ms","start":"2026-04-28T17:26:09.933972Z","end":"2026-04-28T17:26:10.102648Z","steps":["trace[1501440552] 'agreement among raft nodes before linearized reading' (duration: 168.398247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:30.722353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.708005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:26:30.722792Z","caller":"traceutil/trace.go:171","msg":"trace[2127102438] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"152.84316ms","start":"2026-04-28T17:26:30.56968Z","end":"2026-04-28T17:26:30.722524Z","steps":["trace[2127102438] 'process raft request' (duration: 45.88917ms)","trace[2127102438] 'compare' (duration: 106.558421ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:37.85025Z","caller":"traceutil/trace.go:171","msg":"trace[1193822481] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"147.27703ms","start":"2026-04-28T17:26:37.702935Z","end":"2026-04-28T17:26:37.850212Z","steps":["trace[1193822481] 'process raft request' (duration: 147.191579ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:37.850438Z","caller":"traceutil/trace.go:171","msg":"trace[1194461612] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"160.796678ms","start":"2026-04-28T17:26:37.689624Z","end":"2026-04-28T17:26:37.85042Z","steps":["trace[1194461612] 'process raft request' (duration: 137.253264ms)","trace[1194461612] 'compare' (duration: 23.112244ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:48.129098Z","caller":"traceutil/trace.go:171","msg":"trace[1009676405] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"225.979453ms","start":"2026-04-28T17:26:47.903096Z","end":"2026-04-28T17:26:48.129075Z","steps":["trace[1009676405] 'process raft request' (duration: 225.831009ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:48.262013Z","caller":"traceutil/trace.go:171","msg":"trace[1821596017] linearizableReadLoop","detail":"{readStateIndex:4017; appliedIndex:4014; }","duration":"134.379515ms","start":"2026-04-28T17:26:48.127603Z","end":"2026-04-28T17:26:48.261983Z","steps":["trace[1821596017] 'read index received' (duration: 1.400853ms)","trace[1821596017] 'applied index is now lower than readState.Index' (duration: 132.977442ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:48.262058Z","caller":"traceutil/trace.go:171","msg":"trace[1364267207] transaction","detail":"{read_only:false; response_revision:3870; number_of_response:1; }","duration":"339.58413ms","start":"2026-04-28T17:26:47.922452Z","end":"2026-04-28T17:26:48.262036Z","steps":["trace[1364267207] 'process raft request' (duration: 339.448606ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:48.262121Z","caller":"traceutil/trace.go:171","msg":"trace[1640297056] transaction","detail":"{read_only:false; response_revision:3869; number_of_response:1; }","duration":"345.372792ms","start":"2026-04-28T17:26:47.91661Z","end":"2026-04-28T17:26:48.261983Z","steps":["trace[1640297056] 'process raft request' (duration: 302.423044ms)","trace[1640297056] 'compare' (duration: 42.660922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:48.26218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.574169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-prometheus-oauth2-proxy\" ","response":"range_response_count:1 size:2874"} {"level":"info","ts":"2026-04-28T17:26:48.262229Z","caller":"traceutil/trace.go:171","msg":"trace[984458380] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-prometheus-oauth2-proxy; range_end:; response_count:1; response_revision:3870; }","duration":"134.661573ms","start":"2026-04-28T17:26:48.127552Z","end":"2026-04-28T17:26:48.262213Z","steps":["trace[984458380] 'agreement among raft nodes before linearized reading' (duration: 134.527879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:48.262231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:47.916584Z","time spent":"345.573266ms","remote":"127.0.0.1:48940","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-04-28T17:26:48.262231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:47.922438Z","time spent":"339.705632ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:26:49.321626Z","caller":"traceutil/trace.go:171","msg":"trace[1389934349] transaction","detail":"{read_only:false; response_revision:3893; number_of_response:1; }","duration":"197.786172ms","start":"2026-04-28T17:26:49.123823Z","end":"2026-04-28T17:26:49.321609Z","steps":["trace[1389934349] 'process raft request' (duration: 197.643278ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.321852Z","caller":"traceutil/trace.go:171","msg":"trace[45837511] transaction","detail":"{read_only:false; response_revision:3896; number_of_response:1; }","duration":"197.928365ms","start":"2026-04-28T17:26:49.123899Z","end":"2026-04-28T17:26:49.321827Z","steps":["trace[45837511] 'process raft request' (duration: 197.73345ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.321918Z","caller":"traceutil/trace.go:171","msg":"trace[1297830733] transaction","detail":"{read_only:false; response_revision:3895; number_of_response:1; }","duration":"198.001457ms","start":"2026-04-28T17:26:49.123897Z","end":"2026-04-28T17:26:49.321899Z","steps":["trace[1297830733] 'process raft request' (duration: 197.69447ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.3218Z","caller":"traceutil/trace.go:171","msg":"trace[194697535] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"197.859433ms","start":"2026-04-28T17:26:49.123865Z","end":"2026-04-28T17:26:49.321725Z","steps":["trace[194697535] 'process raft request' (duration: 197.683939ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.322165Z","caller":"traceutil/trace.go:171","msg":"trace[230088417] transaction","detail":"{read_only:false; response_revision:3897; number_of_response:1; }","duration":"193.860221ms","start":"2026-04-28T17:26:49.128288Z","end":"2026-04-28T17:26:49.322148Z","steps":["trace[230088417] 'process raft request' (duration: 193.38863ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.322593Z","caller":"traceutil/trace.go:171","msg":"trace[1526273812] transaction","detail":"{read_only:false; response_revision:3892; number_of_response:1; }","duration":"197.777032ms","start":"2026-04-28T17:26:49.123784Z","end":"2026-04-28T17:26:49.321561Z","steps":["trace[1526273812] 'process raft request' (duration: 118.751048ms)","trace[1526273812] 'compare' (duration: 78.783298ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:49.539317Z","caller":"traceutil/trace.go:171","msg":"trace[1642770441] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"140.311616ms","start":"2026-04-28T17:26:49.398972Z","end":"2026-04-28T17:26:49.539283Z","steps":["trace[1642770441] 'process raft request' (duration: 82.762029ms)","trace[1642770441] 'compare' (duration: 57.377243ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:49.544663Z","caller":"traceutil/trace.go:171","msg":"trace[756295351] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"135.631288ms","start":"2026-04-28T17:26:49.409017Z","end":"2026-04-28T17:26:49.544649Z","steps":["trace[756295351] 'process raft request' (duration: 135.535575ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.713643Z","caller":"traceutil/trace.go:171","msg":"trace[1489634467] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3903; }","duration":"103.017446ms","start":"2026-04-28T17:26:49.610596Z","end":"2026-04-28T17:26:49.713613Z","steps":["trace[1489634467] 'process raft request' (duration: 30.437462ms)","trace[1489634467] 'compare' (duration: 72.421991ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:49.906353Z","caller":"traceutil/trace.go:171","msg":"trace[1906520407] linearizableReadLoop","detail":"{readStateIndex:4059; appliedIndex:4058; }","duration":"129.816164ms","start":"2026-04-28T17:26:49.776513Z","end":"2026-04-28T17:26:49.906329Z","steps":["trace[1906520407] 'read index received' (duration: 59.451421ms)","trace[1906520407] 'applied index is now lower than readState.Index' (duration: 70.363543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:49.906473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.940547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:26:49.90651Z","caller":"traceutil/trace.go:171","msg":"trace[1687023230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3907; }","duration":"130.006538ms","start":"2026-04-28T17:26:49.776494Z","end":"2026-04-28T17:26:49.906501Z","steps":["trace[1687023230] 'agreement among raft nodes before linearized reading' (duration: 129.924646ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:49.906498Z","caller":"traceutil/trace.go:171","msg":"trace[1594110414] transaction","detail":"{read_only:false; response_revision:3907; number_of_response:1; }","duration":"130.444669ms","start":"2026-04-28T17:26:49.776028Z","end":"2026-04-28T17:26:49.906472Z","steps":["trace[1594110414] 'process raft request' (duration: 60.001874ms)","trace[1594110414] 'compare' (duration: 70.093837ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:53.096461Z","caller":"traceutil/trace.go:171","msg":"trace[179653225] linearizableReadLoop","detail":"{readStateIndex:4105; appliedIndex:4104; }","duration":"256.044621ms","start":"2026-04-28T17:26:52.840389Z","end":"2026-04-28T17:26:53.096433Z","steps":["trace[179653225] 'read index received' (duration: 184.336189ms)","trace[179653225] 'applied index is now lower than readState.Index' (duration: 71.705891ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:53.096515Z","caller":"traceutil/trace.go:171","msg":"trace[346598340] transaction","detail":"{read_only:false; response_revision:3953; number_of_response:1; }","duration":"282.056483ms","start":"2026-04-28T17:26:52.814423Z","end":"2026-04-28T17:26:53.096479Z","steps":["trace[346598340] 'process raft request' (duration: 210.683909ms)","trace[346598340] 'compare' (duration: 70.78864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:53.096705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.306837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T17:26:53.097872Z","caller":"traceutil/trace.go:171","msg":"trace[1741633722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3954; }","duration":"257.521995ms","start":"2026-04-28T17:26:52.840337Z","end":"2026-04-28T17:26:53.097859Z","steps":["trace[1741633722] 'agreement among raft nodes before linearized reading' (duration: 256.235985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:53.096771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.895296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T17:26:53.098082Z","caller":"traceutil/trace.go:171","msg":"trace[1553030379] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3954; }","duration":"244.250708ms","start":"2026-04-28T17:26:52.853816Z","end":"2026-04-28T17:26:53.098067Z","steps":["trace[1553030379] 'agreement among raft nodes before linearized reading' (duration: 242.813304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:53.096791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.192981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T17:26:53.10307Z","caller":"traceutil/trace.go:171","msg":"trace[1023962958] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3954; }","duration":"115.491447ms","start":"2026-04-28T17:26:52.987543Z","end":"2026-04-28T17:26:53.103034Z","steps":["trace[1023962958] 'agreement among raft nodes before linearized reading' (duration: 109.141349ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:57.640763Z","caller":"traceutil/trace.go:171","msg":"trace[1398765512] transaction","detail":"{read_only:false; response_revision:3984; number_of_response:1; }","duration":"159.522457ms","start":"2026-04-28T17:26:57.481207Z","end":"2026-04-28T17:26:57.64073Z","steps":["trace[1398765512] 'process raft request' (duration: 141.224351ms)","trace[1398765512] 'compare' (duration: 18.03458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:57.900982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.733029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:26:57.901057Z","caller":"traceutil/trace.go:171","msg":"trace[316452582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3984; }","duration":"122.832002ms","start":"2026-04-28T17:26:57.778207Z","end":"2026-04-28T17:26:57.901039Z","steps":["trace[316452582] 'range keys from in-memory index tree' (duration: 122.658698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:57.90119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:26:57.901345Z","caller":"traceutil/trace.go:171","msg":"trace[167305225] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3984; }","duration":"124.282815ms","start":"2026-04-28T17:26:57.776963Z","end":"2026-04-28T17:26:57.901245Z","steps":["trace[167305225] 'count revisions from in-memory index tree' (duration: 123.803695ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:29.616943Z","caller":"traceutil/trace.go:171","msg":"trace[1793999431] transaction","detail":"{read_only:false; response_revision:4166; number_of_response:1; }","duration":"107.02461ms","start":"2026-04-28T17:27:29.509881Z","end":"2026-04-28T17:27:29.616906Z","steps":["trace[1793999431] 'process raft request' (duration: 78.236366ms)","trace[1793999431] 'compare' (duration: 28.318433ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:32.026441Z","caller":"traceutil/trace.go:171","msg":"trace[381795418] transaction","detail":"{read_only:false; response_revision:4195; number_of_response:1; }","duration":"165.979419ms","start":"2026-04-28T17:27:31.860438Z","end":"2026-04-28T17:27:32.026417Z","steps":["trace[381795418] 'process raft request' (duration: 165.815796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:36.094992Z","caller":"traceutil/trace.go:171","msg":"trace[1539089561] transaction","detail":"{read_only:false; response_revision:4220; number_of_response:1; }","duration":"101.182984ms","start":"2026-04-28T17:27:35.993777Z","end":"2026-04-28T17:27:36.09496Z","steps":["trace[1539089561] 'process raft request' (duration: 38.494774ms)","trace[1539089561] 'compare' (duration: 62.570967ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:36.303505Z","caller":"traceutil/trace.go:171","msg":"trace[455429335] transaction","detail":"{read_only:false; response_revision:4221; number_of_response:1; }","duration":"201.053398ms","start":"2026-04-28T17:27:36.102374Z","end":"2026-04-28T17:27:36.303428Z","steps":["trace[455429335] 'process raft request' (duration: 161.645272ms)","trace[455429335] 'compare' (duration: 39.13528ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:36.42753Z","caller":"traceutil/trace.go:171","msg":"trace[1446302087] linearizableReadLoop","detail":"{readStateIndex:4394; appliedIndex:4393; }","duration":"112.397857ms","start":"2026-04-28T17:27:36.315108Z","end":"2026-04-28T17:27:36.427506Z","steps":["trace[1446302087] 'read index received' (duration: 104.443634ms)","trace[1446302087] 'applied index is now lower than readState.Index' (duration: 7.953183ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:36.427542Z","caller":"traceutil/trace.go:171","msg":"trace[1085427157] transaction","detail":"{read_only:false; response_revision:4222; number_of_response:1; }","duration":"116.667921ms","start":"2026-04-28T17:27:36.31085Z","end":"2026-04-28T17:27:36.427518Z","steps":["trace[1085427157] 'process raft request' (duration: 108.616706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:36.427755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.620952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-04-28T17:27:36.427887Z","caller":"traceutil/trace.go:171","msg":"trace[288920751] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4222; }","duration":"112.804736ms","start":"2026-04-28T17:27:36.31507Z","end":"2026-04-28T17:27:36.427874Z","steps":["trace[288920751] 'agreement among raft nodes before linearized reading' (duration: 112.533069ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:38.58365Z","caller":"traceutil/trace.go:171","msg":"trace[1437547461] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"100.592425ms","start":"2026-04-28T17:27:38.483027Z","end":"2026-04-28T17:27:38.58362Z","steps":["trace[1437547461] 'process raft request' (duration: 73.969937ms)","trace[1437547461] 'compare' (duration: 26.288679ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:41.124624Z","caller":"traceutil/trace.go:171","msg":"trace[2076345825] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"192.360795ms","start":"2026-04-28T17:27:40.932239Z","end":"2026-04-28T17:27:41.124599Z","steps":["trace[2076345825] 'process raft request' (duration: 192.221562ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.29829Z","caller":"traceutil/trace.go:171","msg":"trace[864318590] linearizableReadLoop","detail":"{readStateIndex:4761; appliedIndex:4760; }","duration":"223.514555ms","start":"2026-04-28T17:27:41.074751Z","end":"2026-04-28T17:27:41.298266Z","steps":["trace[864318590] 'read index received' (duration: 49.999928ms)","trace[864318590] 'applied index is now lower than readState.Index' (duration: 173.513477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:41.298524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.748042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T17:27:41.298549Z","caller":"traceutil/trace.go:171","msg":"trace[1302346606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4582; }","duration":"223.841464ms","start":"2026-04-28T17:27:41.074701Z","end":"2026-04-28T17:27:41.298543Z","steps":["trace[1302346606] 'agreement among raft nodes before linearized reading' (duration: 223.6837ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.298395Z","caller":"traceutil/trace.go:171","msg":"trace[484943285] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"338.373274ms","start":"2026-04-28T17:27:40.959987Z","end":"2026-04-28T17:27:41.29836Z","steps":["trace[484943285] 'process raft request' (duration: 338.093127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:41.298802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:40.959956Z","time spent":"338.724851ms","remote":"127.0.0.1:48912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3862,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:27:41.298899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.965941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T17:27:41.298922Z","caller":"traceutil/trace.go:171","msg":"trace[1391636631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4582; }","duration":"108.160846ms","start":"2026-04-28T17:27:41.190756Z","end":"2026-04-28T17:27:41.298916Z","steps":["trace[1391636631] 'agreement among raft nodes before linearized reading' (duration: 108.103345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:41.298803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.24964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:27:41.299055Z","caller":"traceutil/trace.go:171","msg":"trace[1767178174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4582; }","duration":"168.577437ms","start":"2026-04-28T17:27:41.130451Z","end":"2026-04-28T17:27:41.299028Z","steps":["trace[1767178174] 'agreement among raft nodes before linearized reading' (duration: 168.033574ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.417161Z","caller":"traceutil/trace.go:171","msg":"trace[105209399] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"111.671781ms","start":"2026-04-28T17:27:41.305462Z","end":"2026-04-28T17:27:41.417134Z","steps":["trace[105209399] 'process raft request' (duration: 111.62514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.41729Z","caller":"traceutil/trace.go:171","msg":"trace[641840796] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"111.993879ms","start":"2026-04-28T17:27:41.305265Z","end":"2026-04-28T17:27:41.417259Z","steps":["trace[641840796] 'process raft request' (duration: 111.714252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.964668Z","caller":"traceutil/trace.go:171","msg":"trace[157123144] linearizableReadLoop","detail":"{readStateIndex:4768; appliedIndex:4767; }","duration":"189.842736ms","start":"2026-04-28T17:27:41.774802Z","end":"2026-04-28T17:27:41.964644Z","steps":["trace[157123144] 'read index received' (duration: 167.906921ms)","trace[157123144] 'applied index is now lower than readState.Index' (duration: 21.935085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:41.964833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.990899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:27:41.964863Z","caller":"traceutil/trace.go:171","msg":"trace[1751529200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4589; }","duration":"190.088242ms","start":"2026-04-28T17:27:41.774765Z","end":"2026-04-28T17:27:41.964853Z","steps":["trace[1751529200] 'agreement among raft nodes before linearized reading' (duration: 189.961459ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:41.964853Z","caller":"traceutil/trace.go:171","msg":"trace[1176120363] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"197.78576ms","start":"2026-04-28T17:27:41.767012Z","end":"2026-04-28T17:27:41.964798Z","steps":["trace[1176120363] 'process raft request' (duration: 175.748632ms)","trace[1176120363] 'compare' (duration: 21.721009ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:42.391922Z","caller":"traceutil/trace.go:171","msg":"trace[753432602] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"258.708229ms","start":"2026-04-28T17:27:42.133194Z","end":"2026-04-28T17:27:42.391902Z","steps":["trace[753432602] 'process raft request' (duration: 258.518064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:46.841956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.747948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-04-28T17:27:46.841956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.53464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:27:46.842033Z","caller":"traceutil/trace.go:171","msg":"trace[1533575283] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4699; }","duration":"195.856711ms","start":"2026-04-28T17:27:46.646154Z","end":"2026-04-28T17:27:46.84201Z","steps":["trace[1533575283] 'range keys from in-memory index tree' (duration: 195.581615ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:46.842057Z","caller":"traceutil/trace.go:171","msg":"trace[948526909] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4699; }","duration":"201.659844ms","start":"2026-04-28T17:27:46.640373Z","end":"2026-04-28T17:27:46.842033Z","steps":["trace[948526909] 'range keys from in-memory index tree' (duration: 201.49701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:46.841951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.524495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-28T17:27:46.842181Z","caller":"traceutil/trace.go:171","msg":"trace[1302516827] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4699; }","duration":"211.818852ms","start":"2026-04-28T17:27:46.630346Z","end":"2026-04-28T17:27:46.842164Z","steps":["trace[1302516827] 'range keys from in-memory index tree' (duration: 211.350091ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:46.842195Z","caller":"traceutil/trace.go:171","msg":"trace[644376565] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"172.873029ms","start":"2026-04-28T17:27:46.669298Z","end":"2026-04-28T17:27:46.842171Z","steps":["trace[644376565] 'process raft request' (duration: 172.459769ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:46.843673Z","caller":"traceutil/trace.go:171","msg":"trace[1314030032] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"131.149898ms","start":"2026-04-28T17:27:46.71248Z","end":"2026-04-28T17:27:46.84363Z","steps":["trace[1314030032] 'process raft request' (duration: 130.78993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:52.784508Z","caller":"traceutil/trace.go:171","msg":"trace[471772391] linearizableReadLoop","detail":"{readStateIndex:4992; appliedIndex:4991; }","duration":"361.265448ms","start":"2026-04-28T17:27:52.423217Z","end":"2026-04-28T17:27:52.784482Z","steps":["trace[471772391] 'read index received' (duration: 298.792033ms)","trace[471772391] 'applied index is now lower than readState.Index' (duration: 62.472415ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:52.784682Z","caller":"traceutil/trace.go:171","msg":"trace[81316005] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"361.574286ms","start":"2026-04-28T17:27:52.423079Z","end":"2026-04-28T17:27:52.784653Z","steps":["trace[81316005] 'process raft request' (duration: 298.868626ms)","trace[81316005] 'compare' (duration: 62.409543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:52.7848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:52.423069Z","time spent":"361.670218ms","remote":"127.0.0.1:48774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:27:52.784922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.680418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9696"} {"level":"info","ts":"2026-04-28T17:27:52.784976Z","caller":"traceutil/trace.go:171","msg":"trace[991606198] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4808; }","duration":"361.769121ms","start":"2026-04-28T17:27:52.423189Z","end":"2026-04-28T17:27:52.784958Z","steps":["trace[991606198] 'agreement among raft nodes before linearized reading' (duration: 361.507794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:52.785017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:52.423177Z","time spent":"361.829442ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9719,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T17:27:52.824376Z","caller":"traceutil/trace.go:171","msg":"trace[494303780] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"399.17829ms","start":"2026-04-28T17:27:52.425164Z","end":"2026-04-28T17:27:52.824343Z","steps":["trace[494303780] 'process raft request' (duration: 398.889193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:52.824543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.440952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-04-28T17:27:52.824605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:52.425147Z","time spent":"399.349953ms","remote":"127.0.0.1:49510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:27:52.824636Z","caller":"traceutil/trace.go:171","msg":"trace[1649079754] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4810; }","duration":"398.590175ms","start":"2026-04-28T17:27:52.426026Z","end":"2026-04-28T17:27:52.824616Z","steps":["trace[1649079754] 'agreement among raft nodes before linearized reading' (duration: 398.274788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:52.824687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:52.426008Z","time spent":"398.668398ms","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"info","ts":"2026-04-28T17:27:52.824908Z","caller":"traceutil/trace.go:171","msg":"trace[1969372369] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"387.7547ms","start":"2026-04-28T17:27:52.437119Z","end":"2026-04-28T17:27:52.824874Z","steps":["trace[1969372369] 'process raft request' (duration: 387.115944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:52.825202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:52.437102Z","time spent":"387.987365ms","remote":"127.0.0.1:49164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:27:52.825774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.415889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-28T17:27:52.832133Z","caller":"traceutil/trace.go:171","msg":"trace[1135291621] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4810; }","duration":"246.802195ms","start":"2026-04-28T17:27:52.585305Z","end":"2026-04-28T17:27:52.832108Z","steps":["trace[1135291621] 'agreement among raft nodes before linearized reading' (duration: 239.453904ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:53.814803Z","caller":"traceutil/trace.go:171","msg":"trace[1901445720] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5018; }","duration":"103.27821ms","start":"2026-04-28T17:27:53.711508Z","end":"2026-04-28T17:27:53.814786Z","steps":["trace[1901445720] 'read index received' (duration: 103.151597ms)","trace[1901445720] 'applied index is now lower than readState.Index' (duration: 125.803µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:53.814901Z","caller":"traceutil/trace.go:171","msg":"trace[747333944] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"214.072836ms","start":"2026-04-28T17:27:53.600822Z","end":"2026-04-28T17:27:53.814895Z","steps":["trace[747333944] 'process raft request' (duration: 213.84447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:53.815084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.566907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T17:27:53.815105Z","caller":"traceutil/trace.go:171","msg":"trace[1686849269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4835; }","duration":"103.65806ms","start":"2026-04-28T17:27:53.711442Z","end":"2026-04-28T17:27:53.8151Z","steps":["trace[1686849269] 'agreement among raft nodes before linearized reading' (duration: 103.557187ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.613072Z","caller":"traceutil/trace.go:171","msg":"trace[1990929259] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"247.641994ms","start":"2026-04-28T17:28:03.365403Z","end":"2026-04-28T17:28:03.613045Z","steps":["trace[1990929259] 'process raft request' (duration: 247.263424ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.769024Z","caller":"traceutil/trace.go:171","msg":"trace[586677677] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"140.368278ms","start":"2026-04-28T17:28:03.62863Z","end":"2026-04-28T17:28:03.768998Z","steps":["trace[586677677] 'read index received' (duration: 90.083132ms)","trace[586677677] 'applied index is now lower than readState.Index' (duration: 50.283676ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:03.769062Z","caller":"traceutil/trace.go:171","msg":"trace[157638241] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"151.142835ms","start":"2026-04-28T17:28:03.617887Z","end":"2026-04-28T17:28:03.76903Z","steps":["trace[157638241] 'process raft request' (duration: 100.884509ms)","trace[157638241] 'compare' (duration: 49.62804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:28:03.769263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.612113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T17:28:03.769366Z","caller":"traceutil/trace.go:171","msg":"trace[1199910997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4950; }","duration":"140.749947ms","start":"2026-04-28T17:28:03.628604Z","end":"2026-04-28T17:28:03.769354Z","steps":["trace[1199910997] 'agreement among raft nodes before linearized reading' (duration: 140.477741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:03.769294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.37626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T17:28:03.769533Z","caller":"traceutil/trace.go:171","msg":"trace[2042619467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4950; }","duration":"136.633525ms","start":"2026-04-28T17:28:03.632881Z","end":"2026-04-28T17:28:03.769515Z","steps":["trace[2042619467] 'agreement among raft nodes before linearized reading' (duration: 136.271426ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.970696Z","caller":"traceutil/trace.go:171","msg":"trace[419538693] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5139; }","duration":"194.319054ms","start":"2026-04-28T17:28:03.776353Z","end":"2026-04-28T17:28:03.970672Z","steps":["trace[419538693] 'read index received' (duration: 100.882309ms)","trace[419538693] 'applied index is now lower than readState.Index' (duration: 93.435775ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:03.970829Z","caller":"traceutil/trace.go:171","msg":"trace[1983120634] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"197.175314ms","start":"2026-04-28T17:28:03.773642Z","end":"2026-04-28T17:28:03.970817Z","steps":["trace[1983120634] 'process raft request' (duration: 196.899037ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.970821Z","caller":"traceutil/trace.go:171","msg":"trace[1839697613] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"197.78843ms","start":"2026-04-28T17:28:03.772995Z","end":"2026-04-28T17:28:03.970783Z","steps":["trace[1839697613] 'process raft request' (duration: 104.208683ms)","trace[1839697613] 'compare' (duration: 93.039024ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:03.971041Z","caller":"traceutil/trace.go:171","msg":"trace[2143157078] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"173.120899ms","start":"2026-04-28T17:28:03.797911Z","end":"2026-04-28T17:28:03.971032Z","steps":["trace[2143157078] 'process raft request' (duration: 172.71869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:03.971205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.840206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:28:03.971245Z","caller":"traceutil/trace.go:171","msg":"trace[534280221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"194.942128ms","start":"2026-04-28T17:28:03.776293Z","end":"2026-04-28T17:28:03.971236Z","steps":["trace[534280221] 'agreement among raft nodes before linearized reading' (duration: 194.842896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:03.971481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.440441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-28T17:28:03.971485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.297873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-28T17:28:03.971481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.072041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T17:28:03.971564Z","caller":"traceutil/trace.go:171","msg":"trace[729110000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"111.531593ms","start":"2026-04-28T17:28:03.859993Z","end":"2026-04-28T17:28:03.971525Z","steps":["trace[729110000] 'agreement among raft nodes before linearized reading' (duration: 111.313418ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.971667Z","caller":"traceutil/trace.go:171","msg":"trace[1460362623] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4953; }","duration":"104.435697ms","start":"2026-04-28T17:28:03.867107Z","end":"2026-04-28T17:28:03.971543Z","steps":["trace[1460362623] 'agreement among raft nodes before linearized reading' (duration: 104.202941ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:03.971653Z","caller":"traceutil/trace.go:171","msg":"trace[1272407024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"115.233924ms","start":"2026-04-28T17:28:03.856378Z","end":"2026-04-28T17:28:03.971611Z","steps":["trace[1272407024] 'agreement among raft nodes before linearized reading' (duration: 114.916427ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:04.264116Z","caller":"traceutil/trace.go:171","msg":"trace[653715602] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"287.19451ms","start":"2026-04-28T17:28:03.976889Z","end":"2026-04-28T17:28:04.264084Z","steps":["trace[653715602] 'process raft request' (duration: 287.127888ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:04.264153Z","caller":"traceutil/trace.go:171","msg":"trace[300866898] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"287.319232ms","start":"2026-04-28T17:28:03.976794Z","end":"2026-04-28T17:28:04.264113Z","steps":["trace[300866898] 'process raft request' (duration: 287.184869ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:04.264153Z","caller":"traceutil/trace.go:171","msg":"trace[729518422] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"288.604183ms","start":"2026-04-28T17:28:03.975509Z","end":"2026-04-28T17:28:04.264113Z","steps":["trace[729518422] 'process raft request' (duration: 288.223184ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:04.264338Z","caller":"traceutil/trace.go:171","msg":"trace[39731094] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"206.060618ms","start":"2026-04-28T17:28:04.058217Z","end":"2026-04-28T17:28:04.264278Z","steps":["trace[39731094] 'process raft request' (duration: 205.832513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:04.669848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.603336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:28:04.670118Z","caller":"traceutil/trace.go:171","msg":"trace[275410779] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5143; }","duration":"567.473508ms","start":"2026-04-28T17:28:04.102625Z","end":"2026-04-28T17:28:04.670098Z","steps":["trace[275410779] 'read index received' (duration: 161.139155ms)","trace[275410779] 'applied index is now lower than readState.Index' (duration: 406.332413ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:04.670159Z","caller":"traceutil/trace.go:171","msg":"trace[1898334277] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"566.091983ms","start":"2026-04-28T17:28:04.104029Z","end":"2026-04-28T17:28:04.670121Z","steps":["trace[1898334277] 'process raft request' (duration: 565.924619ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:04.670169Z","caller":"traceutil/trace.go:171","msg":"trace[1533194251] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"599.823689ms","start":"2026-04-28T17:28:04.070301Z","end":"2026-04-28T17:28:04.670124Z","steps":["trace[1533194251] 'process raft request' (duration: 222.851604ms)","trace[1533194251] 'compare' (duration: 376.498093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:28:04.670315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:04.104017Z","time spent":"566.213596ms","remote":"127.0.0.1:48774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:28:04.670332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.671182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-2xzb9\" ","response":"range_response_count:1 size:9716"} {"level":"warn","ts":"2026-04-28T17:28:04.670336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:04.070103Z","time spent":"600.141928ms","remote":"127.0.0.1:48724","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-04-28T17:28:04.670405Z","caller":"traceutil/trace.go:171","msg":"trace[1068345013] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-2xzb9; range_end:; response_count:1; response_revision:4959; }","duration":"567.779325ms","start":"2026-04-28T17:28:04.102585Z","end":"2026-04-28T17:28:04.670364Z","steps":["trace[1068345013] 'agreement among raft nodes before linearized reading' (duration: 567.57474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:04.670456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:04.102566Z","time spent":"567.878727ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9739,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-2xzb9\" "} {"level":"info","ts":"2026-04-28T17:28:10.43532Z","caller":"traceutil/trace.go:171","msg":"trace[594733215] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"112.411589ms","start":"2026-04-28T17:28:10.322875Z","end":"2026-04-28T17:28:10.435287Z","steps":["trace[594733215] 'process raft request' (duration: 112.373608ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:10.43533Z","caller":"traceutil/trace.go:171","msg":"trace[1969616946] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"112.984282ms","start":"2026-04-28T17:28:10.322318Z","end":"2026-04-28T17:28:10.435302Z","steps":["trace[1969616946] 'process raft request' (duration: 112.811788ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:11.294324Z","caller":"traceutil/trace.go:171","msg":"trace[2050669089] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"123.696209ms","start":"2026-04-28T17:28:11.170588Z","end":"2026-04-28T17:28:11.294284Z","steps":["trace[2050669089] 'process raft request' (duration: 123.32941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:11.48269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.680092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:28:11.482818Z","caller":"traceutil/trace.go:171","msg":"trace[2070568506] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"182.195357ms","start":"2026-04-28T17:28:11.3006Z","end":"2026-04-28T17:28:11.482796Z","steps":["trace[2070568506] 'read index received' (duration: 80.32337ms)","trace[2070568506] 'applied index is now lower than readState.Index' (duration: 101.870447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:28:11.483124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.512056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-27f037f4-d403-4f8b-8029-af3c7eae6fc7\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-28T17:28:11.483191Z","caller":"traceutil/trace.go:171","msg":"trace[1025862879] range","detail":"{range_begin:/registry/persistentvolumes/pvc-27f037f4-d403-4f8b-8029-af3c7eae6fc7; range_end:; response_count:1; response_revision:5030; }","duration":"182.608179ms","start":"2026-04-28T17:28:11.300556Z","end":"2026-04-28T17:28:11.483164Z","steps":["trace[1025862879] 'agreement among raft nodes before linearized reading' (duration: 182.298982ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:11.483452Z","caller":"traceutil/trace.go:171","msg":"trace[1665170330] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"182.906056ms","start":"2026-04-28T17:28:11.300497Z","end":"2026-04-28T17:28:11.483403Z","steps":["trace[1665170330] 'process raft request' (duration: 80.421583ms)","trace[1665170330] 'compare' (duration: 101.550589ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:11.500359Z","caller":"traceutil/trace.go:171","msg":"trace[2102795094] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"199.182801ms","start":"2026-04-28T17:28:11.301155Z","end":"2026-04-28T17:28:11.500338Z","steps":["trace[2102795094] 'process raft request' (duration: 199.13144ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:11.500357Z","caller":"traceutil/trace.go:171","msg":"trace[911844704] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"199.526489ms","start":"2026-04-28T17:28:11.300803Z","end":"2026-04-28T17:28:11.500329Z","steps":["trace[911844704] 'process raft request' (duration: 199.295463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:11.500482Z","caller":"traceutil/trace.go:171","msg":"trace[120655522] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"199.644732ms","start":"2026-04-28T17:28:11.300826Z","end":"2026-04-28T17:28:11.500471Z","steps":["trace[120655522] 'process raft request' (duration: 199.413417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.041252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696381057283001,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T17:28:43.07403Z","caller":"traceutil/trace.go:171","msg":"trace[1773615876] linearizableReadLoop","detail":"{readStateIndex:5568; appliedIndex:5567; }","duration":"533.533264ms","start":"2026-04-28T17:28:42.540474Z","end":"2026-04-28T17:28:43.074007Z","steps":["trace[1773615876] 'read index received' (duration: 531.114352ms)","trace[1773615876] 'applied index is now lower than readState.Index' (duration: 2.417892ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:43.074505Z","caller":"traceutil/trace.go:171","msg":"trace[1462130637] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"534.448006ms","start":"2026-04-28T17:28:42.540043Z","end":"2026-04-28T17:28:43.074491Z","steps":["trace[1462130637] 'process raft request' (duration: 531.556763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.074609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.54002Z","time spent":"534.520818ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:28:43.07461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.052467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-04-28T17:28:43.074705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.275262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-api\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T17:28:43.074776Z","caller":"traceutil/trace.go:171","msg":"trace[306551509] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-api; range_end:; response_count:1; response_revision:5368; }","duration":"461.394334ms","start":"2026-04-28T17:28:42.613363Z","end":"2026-04-28T17:28:43.074757Z","steps":["trace[306551509] 'agreement among raft nodes before linearized reading' (duration: 461.159669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.074813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.613343Z","time spent":"461.463397ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":551,"request content":"key:\"/registry/serviceaccounts/openstack/keystone-api\" "} {"level":"info","ts":"2026-04-28T17:28:43.074828Z","caller":"traceutil/trace.go:171","msg":"trace[272394488] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5368; }","duration":"534.297692ms","start":"2026-04-28T17:28:42.54044Z","end":"2026-04-28T17:28:43.074737Z","steps":["trace[272394488] 'agreement among raft nodes before linearized reading' (duration: 533.931363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.074884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.332047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:28:43.074968Z","caller":"traceutil/trace.go:171","msg":"trace[43639453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5368; }","duration":"297.47106ms","start":"2026-04-28T17:28:42.777478Z","end":"2026-04-28T17:28:43.074949Z","steps":["trace[43639453] 'agreement among raft nodes before linearized reading' (duration: 297.312366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.075001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.721926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-28T17:28:43.075037Z","caller":"traceutil/trace.go:171","msg":"trace[426903456] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5368; }","duration":"368.782678ms","start":"2026-04-28T17:28:42.706245Z","end":"2026-04-28T17:28:43.075028Z","steps":["trace[426903456] 'agreement among raft nodes before linearized reading' (duration: 368.706936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.074973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.540432Z","time spent":"534.488518ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-04-28T17:28:43.075153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.329948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:28:43.075188Z","caller":"traceutil/trace.go:171","msg":"trace[1067786576] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5368; }","duration":"198.379529ms","start":"2026-04-28T17:28:42.876797Z","end":"2026-04-28T17:28:43.075177Z","steps":["trace[1067786576] 'agreement among raft nodes before linearized reading' (duration: 198.223675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.075195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.009576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:28:43.075222Z","caller":"traceutil/trace.go:171","msg":"trace[627471111] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5368; }","duration":"392.082878ms","start":"2026-04-28T17:28:42.683133Z","end":"2026-04-28T17:28:43.075216Z","steps":["trace[627471111] 'agreement among raft nodes before linearized reading' (duration: 391.971385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.075248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.683112Z","time spent":"392.12857ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":80,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-28T17:28:43.075251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.789783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-28T17:28:43.075284Z","caller":"traceutil/trace.go:171","msg":"trace[1903033458] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5368; }","duration":"296.842244ms","start":"2026-04-28T17:28:42.778434Z","end":"2026-04-28T17:28:43.075276Z","steps":["trace[1903033458] 'agreement among raft nodes before linearized reading' (duration: 296.713441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.07509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.70623Z","time spent":"368.85384ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":577,"request content":"key:\"/registry/serviceaccounts/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-28T17:28:43.074975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.551394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T17:28:43.075589Z","caller":"traceutil/trace.go:171","msg":"trace[1292119161] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5368; }","duration":"244.167919ms","start":"2026-04-28T17:28:42.831412Z","end":"2026-04-28T17:28:43.07558Z","steps":["trace[1292119161] 'agreement among raft nodes before linearized reading' (duration: 243.41592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.075452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.124347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T17:28:43.075654Z","caller":"traceutil/trace.go:171","msg":"trace[723910487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5368; }","duration":"416.361002ms","start":"2026-04-28T17:28:42.659277Z","end":"2026-04-28T17:28:43.075638Z","steps":["trace[723910487] 'agreement among raft nodes before linearized reading' (duration: 416.081035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:43.075708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:28:42.659256Z","time spent":"416.442615ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T17:28:44.253198Z","caller":"traceutil/trace.go:171","msg":"trace[863790989] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5585; }","duration":"223.895159ms","start":"2026-04-28T17:28:44.029277Z","end":"2026-04-28T17:28:44.253173Z","steps":["trace[863790989] 'read index received' (duration: 223.635222ms)","trace[863790989] 'applied index is now lower than readState.Index' (duration: 258.777µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:44.253248Z","caller":"traceutil/trace.go:171","msg":"trace[296980576] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"260.819917ms","start":"2026-04-28T17:28:43.992401Z","end":"2026-04-28T17:28:44.253221Z","steps":["trace[296980576] 'process raft request' (duration: 260.613441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:44.253389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.087455ms","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-04-28T17:28:44.253462Z","caller":"traceutil/trace.go:171","msg":"trace[2084701307] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5385; }","duration":"224.220059ms","start":"2026-04-28T17:28:44.029227Z","end":"2026-04-28T17:28:44.253447Z","steps":["trace[2084701307] 'agreement among raft nodes before linearized reading' (duration: 224.084915ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:44.428134Z","caller":"traceutil/trace.go:171","msg":"trace[2071802935] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"170.016154ms","start":"2026-04-28T17:28:44.258084Z","end":"2026-04-28T17:28:44.4281Z","steps":["trace[2071802935] 'process raft request' (duration: 150.718924ms)","trace[2071802935] 'compare' (duration: 19.094114ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:28:46.411568Z","caller":"traceutil/trace.go:171","msg":"trace[522448064] linearizableReadLoop","detail":"{readStateIndex:5603; appliedIndex:5602; }","duration":"139.608905ms","start":"2026-04-28T17:28:46.27194Z","end":"2026-04-28T17:28:46.411549Z","steps":["trace[522448064] 'read index received' (duration: 139.555144ms)","trace[522448064] 'applied index is now lower than readState.Index' (duration: 52.901µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:28:46.411876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.450171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:28:46.411857Z","caller":"traceutil/trace.go:171","msg":"trace[1317206620] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"150.361178ms","start":"2026-04-28T17:28:46.261439Z","end":"2026-04-28T17:28:46.4118Z","steps":["trace[1317206620] 'process raft request' (duration: 149.966788ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:28:46.411918Z","caller":"traceutil/trace.go:171","msg":"trace[1896805737] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5402; }","duration":"113.507592ms","start":"2026-04-28T17:28:46.298397Z","end":"2026-04-28T17:28:46.411904Z","steps":["trace[1896805737] 'agreement among raft nodes before linearized reading' (duration: 113.43105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:28:46.412145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.17887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:28:46.41225Z","caller":"traceutil/trace.go:171","msg":"trace[1090414090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5402; }","duration":"140.299932ms","start":"2026-04-28T17:28:46.271901Z","end":"2026-04-28T17:28:46.4122Z","steps":["trace[1090414090] 'agreement among raft nodes before linearized reading' (duration: 139.855461ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:00.686518Z","caller":"traceutil/trace.go:171","msg":"trace[1925526962] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"144.147475ms","start":"2026-04-28T17:29:00.542339Z","end":"2026-04-28T17:29:00.686487Z","steps":["trace[1925526962] 'process raft request' (duration: 143.820877ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:00.686579Z","caller":"traceutil/trace.go:171","msg":"trace[1180965456] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"149.141782ms","start":"2026-04-28T17:29:00.537364Z","end":"2026-04-28T17:29:00.686506Z","steps":["trace[1180965456] 'process raft request' (duration: 95.328066ms)","trace[1180965456] 'compare' (duration: 52.890932ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:29:00.686829Z","caller":"traceutil/trace.go:171","msg":"trace[1616587341] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"123.192708ms","start":"2026-04-28T17:29:00.563576Z","end":"2026-04-28T17:29:00.686769Z","steps":["trace[1616587341] 'process raft request' (duration: 122.667225ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:00.915844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2906} {"level":"warn","ts":"2026-04-28T17:29:01.296688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.403243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T17:29:01.296794Z","caller":"traceutil/trace.go:171","msg":"trace[1389164721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5487; }","duration":"103.558157ms","start":"2026-04-28T17:29:01.193209Z","end":"2026-04-28T17:29:01.296767Z","steps":["trace[1389164721] 'range keys from in-memory index tree' (duration: 103.029073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:29:01.370609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.183481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:29:01.370957Z","caller":"traceutil/trace.go:171","msg":"trace[1952630037] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"163.394147ms","start":"2026-04-28T17:29:01.20751Z","end":"2026-04-28T17:29:01.370904Z","steps":["trace[1952630037] 'process raft request' (duration: 21.824367ms)","trace[1952630037] 'compare' (duration: 67.090325ms)","trace[1952630037] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 73.768857ms)"],"step_count":3} {"level":"info","ts":"2026-04-28T17:29:01.370997Z","caller":"traceutil/trace.go:171","msg":"trace[1911892562] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"163.041678ms","start":"2026-04-28T17:29:01.207922Z","end":"2026-04-28T17:29:01.370964Z","steps":["trace[1911892562] 'process raft request' (duration: 162.934325ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.37111Z","caller":"traceutil/trace.go:171","msg":"trace[32725567] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5693; }","duration":"156.185083ms","start":"2026-04-28T17:29:01.214897Z","end":"2026-04-28T17:29:01.371082Z","steps":["trace[32725567] 'read index received' (duration: 14.439619ms)","trace[32725567] 'applied index is now lower than readState.Index' (duration: 141.743404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:29:01.371339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.428109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-28T17:29:01.37139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T17:29:01.371388Z","caller":"traceutil/trace.go:171","msg":"trace[977846018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5490; }","duration":"156.524421ms","start":"2026-04-28T17:29:01.214845Z","end":"2026-04-28T17:29:01.371369Z","steps":["trace[977846018] 'agreement among raft nodes before linearized reading' (duration: 156.375318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.371431Z","caller":"traceutil/trace.go:171","msg":"trace[1667644774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5490; }","duration":"137.007413ms","start":"2026-04-28T17:29:01.234414Z","end":"2026-04-28T17:29:01.371421Z","steps":["trace[1667644774] 'agreement among raft nodes before linearized reading' (duration: 136.878579ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.415509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2906,"took":"497.897939ms","hash":3195896875,"current-db-size-bytes":52940800,"current-db-size":"53 MB","current-db-size-in-use-bytes":28598272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T17:29:01.415565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195896875,"revision":2906,"compact-revision":-1} {"level":"info","ts":"2026-04-28T17:29:40.931907Z","caller":"traceutil/trace.go:171","msg":"trace[1122777012] linearizableReadLoop","detail":"{readStateIndex:5985; appliedIndex:5984; }","duration":"134.777855ms","start":"2026-04-28T17:29:40.797106Z","end":"2026-04-28T17:29:40.931883Z","steps":["trace[1122777012] 'read index received' (duration: 134.635091ms)","trace[1122777012] 'applied index is now lower than readState.Index' (duration: 141.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:29:40.932022Z","caller":"traceutil/trace.go:171","msg":"trace[1820926534] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"149.047385ms","start":"2026-04-28T17:29:40.782918Z","end":"2026-04-28T17:29:40.931965Z","steps":["trace[1820926534] 'process raft request' (duration: 148.824659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:29:40.932207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.100043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T17:29:40.932258Z","caller":"traceutil/trace.go:171","msg":"trace[635855869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5771; }","duration":"135.482153ms","start":"2026-04-28T17:29:40.796761Z","end":"2026-04-28T17:29:40.932243Z","steps":["trace[635855869] 'agreement among raft nodes before linearized reading' (duration: 135.241087ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:31:50.2338Z","caller":"traceutil/trace.go:171","msg":"trace[1640070255] transaction","detail":"{read_only:false; response_revision:6677; number_of_response:1; }","duration":"170.185073ms","start":"2026-04-28T17:31:50.063588Z","end":"2026-04-28T17:31:50.233773Z","steps":["trace[1640070255] 'process raft request' (duration: 121.287914ms)","trace[1640070255] 'compare' (duration: 48.639112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:31:50.582239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.16703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T17:31:50.582302Z","caller":"traceutil/trace.go:171","msg":"trace[845789576] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6679; }","duration":"162.278823ms","start":"2026-04-28T17:31:50.42001Z","end":"2026-04-28T17:31:50.582289Z","steps":["trace[845789576] 'range keys from in-memory index tree' (duration: 162.073657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:34:00.922287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5487} {"level":"info","ts":"2026-04-28T17:34:01.005717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5487,"took":"81.922396ms","hash":2316078143,"current-db-size-bytes":52940800,"current-db-size":"53 MB","current-db-size-in-use-bytes":18587648,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-28T17:34:01.00578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2316078143,"revision":5487,"compact-revision":2906}