{"level":"warn","ts":"2026-04-22T06:01:38.426644Z","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-22T06:01:38.426786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-22T06:01:38.426908Z","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-22T06:01:38.426924Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-22T06:01:38.426957Z","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-22T06:01:38.428478Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-22T06:01:38.428571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-22T06:01:38.432648Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.838044ms"} {"level":"info","ts":"2026-04-22T06:01:38.442806Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-22T06:01:38.442892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:01:38.442921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-22T06:01:38.442935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:01:38.442942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-22T06:01:38.442995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-22T06:01:38.453165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:01:38.457182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:01:38.459611Z","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-22T06:01:38.462737Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:01:38.46295Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:01:38.463137Z","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-22T06:01:38.463241Z","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-22T06:01:38.463274Z","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-22T06:01:38.467245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-22T06:01:38.469212Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-22T06:01:38.471714Z","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-22T06:01:38.471854Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-22T06:01:38.47222Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-22T06:01:38.472185Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:01:38.472207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:01:39.243396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:01:39.243446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:01:39.243469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-22T06:01:39.243481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:01:39.243486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-22T06:01:39.243494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-22T06:01:39.243501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-22T06:01:39.244334Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:39.245145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:01:39.245175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:01:39.245452Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:39.245534Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:39.245562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:39.245504Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:01:39.245705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:01:39.245794Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:01:39.248655Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-22T06:01:39.249057Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:01:58.859247Z","caller":"traceutil/trace.go:171","msg":"trace[1241536429] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"196.743527ms","start":"2026-04-22T06:01:58.662479Z","end":"2026-04-22T06:01:58.859222Z","steps":["trace[1241536429] 'read index received' (duration: 196.567764ms)","trace[1241536429] 'applied index is now lower than readState.Index' (duration: 174.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:01:58.859326Z","caller":"traceutil/trace.go:171","msg":"trace[1894039504] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"221.297303ms","start":"2026-04-22T06:01:58.638001Z","end":"2026-04-22T06:01:58.859298Z","steps":["trace[1894039504] 'process raft request' (duration: 221.05192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:01:58.859512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.001671ms","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-22T06:01:58.859652Z","caller":"traceutil/trace.go:171","msg":"trace[1863393663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"197.207194ms","start":"2026-04-22T06:01:58.662422Z","end":"2026-04-22T06:01:58.859629Z","steps":["trace[1863393663] 'agreement among raft nodes before linearized reading' (duration: 196.95411ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.356475Z","caller":"traceutil/trace.go:171","msg":"trace[1173575170] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"110.571183ms","start":"2026-04-22T06:02:12.245879Z","end":"2026-04-22T06:02:12.35645Z","steps":["trace[1173575170] 'process raft request' (duration: 110.536342ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.356745Z","caller":"traceutil/trace.go:171","msg":"trace[572748310] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"114.242365ms","start":"2026-04-22T06:02:12.24249Z","end":"2026-04-22T06:02:12.356733Z","steps":["trace[572748310] 'process raft request' (duration: 113.677664ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.356804Z","caller":"traceutil/trace.go:171","msg":"trace[1421124389] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"111.055912ms","start":"2026-04-22T06:02:12.245696Z","end":"2026-04-22T06:02:12.356752Z","steps":["trace[1421124389] 'process raft request' (duration: 110.691075ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.356809Z","caller":"traceutil/trace.go:171","msg":"trace[383291316] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"113.44467ms","start":"2026-04-22T06:02:12.2433Z","end":"2026-04-22T06:02:12.356744Z","steps":["trace[383291316] 'process raft request' (duration: 113.045932ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.35689Z","caller":"traceutil/trace.go:171","msg":"trace[1735181654] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"114.48234ms","start":"2026-04-22T06:02:12.242397Z","end":"2026-04-22T06:02:12.356879Z","steps":["trace[1735181654] 'process raft request' (duration: 65.497761ms)","trace[1735181654] 'compare' (duration: 47.889948ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:02:12.357099Z","caller":"traceutil/trace.go:171","msg":"trace[425889439] transaction","detail":"{read_only:false; number_of_response:1; response_revision:484; }","duration":"114.178844ms","start":"2026-04-22T06:02:12.242903Z","end":"2026-04-22T06:02:12.357082Z","steps":["trace[425889439] 'process raft request' (duration: 113.337838ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:12.364694Z","caller":"traceutil/trace.go:171","msg":"trace[1099539687] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"111.340298ms","start":"2026-04-22T06:02:12.253343Z","end":"2026-04-22T06:02:12.364684Z","steps":["trace[1099539687] 'process raft request' (duration: 110.825268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:18.229281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.670971ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:02:18.435522Z","caller":"traceutil/trace.go:171","msg":"trace[674199078] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"327.394405ms","start":"2026-04-22T06:02:18.108036Z","end":"2026-04-22T06:02:18.43543Z","steps":["trace[674199078] 'process raft request' (duration: 325.842081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:18.437251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:18.10799Z","time spent":"327.768473ms","remote":"127.0.0.1:43838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:02:19.021156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.283477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:02:19.021265Z","caller":"traceutil/trace.go:171","msg":"trace[784136654] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"482.775736ms","start":"2026-04-22T06:02:18.538468Z","end":"2026-04-22T06:02:19.021244Z","steps":["trace[784136654] 'read index received' (duration: 311.319175ms)","trace[784136654] 'applied index is now lower than readState.Index' (duration: 171.455411ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:02:19.021334Z","caller":"traceutil/trace.go:171","msg":"trace[883541298] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"525.293641ms","start":"2026-04-22T06:02:18.496006Z","end":"2026-04-22T06:02:19.0213Z","steps":["trace[883541298] 'process raft request' (duration: 353.781458ms)","trace[883541298] 'compare' (duration: 171.106504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:19.021439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.97315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:02:19.021566Z","caller":"traceutil/trace.go:171","msg":"trace[114548899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"483.155254ms","start":"2026-04-22T06:02:18.538398Z","end":"2026-04-22T06:02:19.021554Z","steps":["trace[114548899] 'agreement among raft nodes before linearized reading' (duration: 482.97022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:19.021654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:18.538363Z","time spent":"483.279127ms","remote":"127.0.0.1:43746","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-22T06:02:19.021491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:18.495967Z","time spent":"525.447314ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:19.022568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.522012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5lv6d\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T06:02:19.022682Z","caller":"traceutil/trace.go:171","msg":"trace[796000477] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5lv6d; range_end:; response_count:1; response_revision:584; }","duration":"385.674236ms","start":"2026-04-22T06:02:18.636985Z","end":"2026-04-22T06:02:19.022659Z","steps":["trace[796000477] 'agreement among raft nodes before linearized reading' (duration: 384.46887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:19.022807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:18.636941Z","time spent":"385.80677ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-5lv6d\" "} {"level":"info","ts":"2026-04-22T06:03:49.321143Z","caller":"traceutil/trace.go:171","msg":"trace[1814410263] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"123.982568ms","start":"2026-04-22T06:03:49.197125Z","end":"2026-04-22T06:03:49.321107Z","steps":["trace[1814410263] 'read index received' (duration: 123.622165ms)","trace[1814410263] 'applied index is now lower than readState.Index' (duration: 355.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:49.321292Z","caller":"traceutil/trace.go:171","msg":"trace[818181087] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"170.35161ms","start":"2026-04-22T06:03:49.15091Z","end":"2026-04-22T06:03:49.321262Z","steps":["trace[818181087] 'process raft request' (duration: 169.943916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.3214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.254497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-865fea87-38b9-4131-b5d9-73e406562c89\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-22T06:03:49.321444Z","caller":"traceutil/trace.go:171","msg":"trace[1005920836] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-865fea87-38b9-4131-b5d9-73e406562c89; range_end:; response_count:1; response_revision:1811; }","duration":"124.34936ms","start":"2026-04-22T06:03:49.197084Z","end":"2026-04-22T06:03:49.321433Z","steps":["trace[1005920836] 'agreement among raft nodes before linearized reading' (duration: 124.163334ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.543643Z","caller":"traceutil/trace.go:171","msg":"trace[1334262583] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"212.338028ms","start":"2026-04-22T06:03:49.331273Z","end":"2026-04-22T06:03:49.543611Z","steps":["trace[1334262583] 'process raft request' (duration: 140.865028ms)","trace[1334262583] 'compare' (duration: 71.312395ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:49.595672Z","caller":"traceutil/trace.go:171","msg":"trace[1997460831] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"264.073659ms","start":"2026-04-22T06:03:49.331566Z","end":"2026-04-22T06:03:49.59564Z","steps":["trace[1997460831] 'process raft request' (duration: 263.779369ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.596344Z","caller":"traceutil/trace.go:171","msg":"trace[1017852697] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"262.979341ms","start":"2026-04-22T06:03:49.333272Z","end":"2026-04-22T06:03:49.596251Z","steps":["trace[1017852697] 'process raft request' (duration: 262.241976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.596445Z","caller":"traceutil/trace.go:171","msg":"trace[1652481893] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"261.725546ms","start":"2026-04-22T06:03:49.334703Z","end":"2026-04-22T06:03:49.596428Z","steps":["trace[1652481893] 'process raft request' (duration: 261.295621ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.596433Z","caller":"traceutil/trace.go:171","msg":"trace[1878646117] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1887; }","duration":"116.851419ms","start":"2026-04-22T06:03:49.47954Z","end":"2026-04-22T06:03:49.596392Z","steps":["trace[1878646117] 'read index received' (duration: 116.533468ms)","trace[1878646117] 'applied index is now lower than readState.Index' (duration: 316.001µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:49.596474Z","caller":"traceutil/trace.go:171","msg":"trace[508704539] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"259.087515ms","start":"2026-04-22T06:03:49.33735Z","end":"2026-04-22T06:03:49.596437Z","steps":["trace[508704539] 'process raft request' (duration: 258.903298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.596868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.300404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-22T06:03:49.596945Z","caller":"traceutil/trace.go:171","msg":"trace[579395926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"117.510261ms","start":"2026-04-22T06:03:49.47941Z","end":"2026-04-22T06:03:49.59692Z","steps":["trace[579395926] 'agreement among raft nodes before linearized reading' (duration: 117.166059ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.722067Z","caller":"traceutil/trace.go:171","msg":"trace[250365902] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"125.504711ms","start":"2026-04-22T06:03:49.596528Z","end":"2026-04-22T06:03:49.722033Z","steps":["trace[250365902] 'read index received' (duration: 51.664738ms)","trace[250365902] 'applied index is now lower than readState.Index' (duration: 73.838453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:03:49.722339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.540851ms","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-22T06:03:49.722384Z","caller":"traceutil/trace.go:171","msg":"trace[1973713193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1817; }","duration":"215.668495ms","start":"2026-04-22T06:03:49.506703Z","end":"2026-04-22T06:03:49.722372Z","steps":["trace[1973713193] 'agreement among raft nodes before linearized reading' (duration: 215.447898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.722685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.892129ms","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-22T06:03:49.722721Z","caller":"traceutil/trace.go:171","msg":"trace[668818040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1817; }","duration":"176.966931ms","start":"2026-04-22T06:03:49.545745Z","end":"2026-04-22T06:03:49.722712Z","steps":["trace[668818040] 'agreement among raft nodes before linearized reading' (duration: 176.874468ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.722767Z","caller":"traceutil/trace.go:171","msg":"trace[238581835] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"163.142238ms","start":"2026-04-22T06:03:49.55958Z","end":"2026-04-22T06:03:49.722723Z","steps":["trace[238581835] 'process raft request' (duration: 88.682343ms)","trace[238581835] 'compare' (duration: 73.613545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:03:49.722853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.446847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:03:49.722883Z","caller":"traceutil/trace.go:171","msg":"trace[791590725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1817; }","duration":"181.488349ms","start":"2026-04-22T06:03:49.541386Z","end":"2026-04-22T06:03:49.722875Z","steps":["trace[791590725] 'agreement among raft nodes before linearized reading' (duration: 181.442197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.722992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.117166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-22T06:03:49.723047Z","caller":"traceutil/trace.go:171","msg":"trace[1076209798] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1817; }","duration":"133.258401ms","start":"2026-04-22T06:03:49.589773Z","end":"2026-04-22T06:03:49.723031Z","steps":["trace[1076209798] 'agreement among raft nodes before linearized reading' (duration: 132.965881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.723102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.401181ms","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-22T06:03:49.723142Z","caller":"traceutil/trace.go:171","msg":"trace[3563804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1817; }","duration":"182.473404ms","start":"2026-04-22T06:03:49.540659Z","end":"2026-04-22T06:03:49.723132Z","steps":["trace[3563804] 'agreement among raft nodes before linearized reading' (duration: 182.341169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.723338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.086185ms","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-22T06:03:49.723391Z","caller":"traceutil/trace.go:171","msg":"trace[1071966616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1817; }","duration":"175.157787ms","start":"2026-04-22T06:03:49.548221Z","end":"2026-04-22T06:03:49.723379Z","steps":["trace[1071966616] 'agreement among raft nodes before linearized reading' (duration: 174.417552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:49.723471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.786896ms","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-22T06:03:49.723501Z","caller":"traceutil/trace.go:171","msg":"trace[92037975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1817; }","duration":"130.836018ms","start":"2026-04-22T06:03:49.592657Z","end":"2026-04-22T06:03:49.723493Z","steps":["trace[92037975] 'agreement among raft nodes before linearized reading' (duration: 130.754064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:50.009693Z","caller":"traceutil/trace.go:171","msg":"trace[467057348] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"279.107645ms","start":"2026-04-22T06:03:49.730549Z","end":"2026-04-22T06:03:50.009656Z","steps":["trace[467057348] 'process raft request' (duration: 264.885548ms)","trace[467057348] 'compare' (duration: 13.744311ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:50.027077Z","caller":"traceutil/trace.go:171","msg":"trace[894322164] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"295.631365ms","start":"2026-04-22T06:03:49.731415Z","end":"2026-04-22T06:03:50.027046Z","steps":["trace[894322164] 'process raft request' (duration: 295.546432ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:50.027156Z","caller":"traceutil/trace.go:171","msg":"trace[1848754892] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"294.6522ms","start":"2026-04-22T06:03:49.732472Z","end":"2026-04-22T06:03:50.027124Z","steps":["trace[1848754892] 'process raft request' (duration: 294.529556ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:50.027448Z","caller":"traceutil/trace.go:171","msg":"trace[758831102] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"296.194345ms","start":"2026-04-22T06:03:49.731199Z","end":"2026-04-22T06:03:50.027393Z","steps":["trace[758831102] 'process raft request' (duration: 295.550092ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:50.027614Z","caller":"traceutil/trace.go:171","msg":"trace[1428865165] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"296.34061ms","start":"2026-04-22T06:03:49.731261Z","end":"2026-04-22T06:03:50.027602Z","steps":["trace[1428865165] 'process raft request' (duration: 295.616845ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:09.033007Z","caller":"traceutil/trace.go:171","msg":"trace[1338631041] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"146.627029ms","start":"2026-04-22T06:04:08.886352Z","end":"2026-04-22T06:04:09.032979Z","steps":["trace[1338631041] 'process raft request' (duration: 146.455863ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:19.961554Z","caller":"traceutil/trace.go:171","msg":"trace[1790449678] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"121.607415ms","start":"2026-04-22T06:04:19.839913Z","end":"2026-04-22T06:04:19.96152Z","steps":["trace[1790449678] 'process raft request' (duration: 121.386877ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:25.226482Z","caller":"traceutil/trace.go:171","msg":"trace[624594135] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"110.356089ms","start":"2026-04-22T06:04:25.116075Z","end":"2026-04-22T06:04:25.226431Z","steps":["trace[624594135] 'process raft request' (duration: 110.058288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:27.07688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.936784ms","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-22T06:04:27.077021Z","caller":"traceutil/trace.go:171","msg":"trace[1164680289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2070; }","duration":"200.167953ms","start":"2026-04-22T06:04:26.876823Z","end":"2026-04-22T06:04:27.07699Z","steps":["trace[1164680289] 'count revisions from in-memory index tree' (duration: 199.851551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:27.551061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.805091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a8989eec318c19\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-22T06:04:27.551367Z","caller":"traceutil/trace.go:171","msg":"trace[1117546062] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a8989eec318c19; range_end:; response_count:1; response_revision:2071; }","duration":"131.197855ms","start":"2026-04-22T06:04:27.420104Z","end":"2026-04-22T06:04:27.551302Z","steps":["trace[1117546062] 'range keys from in-memory index tree' (duration: 130.578303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:42.246391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.942472ms","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-22T06:05:42.246543Z","caller":"traceutil/trace.go:171","msg":"trace[1567015839] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2571; }","duration":"124.155262ms","start":"2026-04-22T06:05:42.122368Z","end":"2026-04-22T06:05:42.246523Z","steps":["trace[1567015839] 'count revisions from in-memory index tree' (duration: 123.858992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:09.314709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.070683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:09.314813Z","caller":"traceutil/trace.go:171","msg":"trace[248123678] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2724; }","duration":"240.238224ms","start":"2026-04-22T06:06:09.074553Z","end":"2026-04-22T06:06:09.314791Z","steps":["trace[248123678] 'count revisions from in-memory index tree' (duration: 239.975863ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.887009Z","caller":"traceutil/trace.go:171","msg":"trace[1851221378] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"143.398304ms","start":"2026-04-22T06:06:14.743587Z","end":"2026-04-22T06:06:14.886985Z","steps":["trace[1851221378] 'process raft request' (duration: 143.258633ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.895318Z","caller":"traceutil/trace.go:171","msg":"trace[1006701108] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"151.55059ms","start":"2026-04-22T06:06:14.743745Z","end":"2026-04-22T06:06:14.895296Z","steps":["trace[1006701108] 'process raft request' (duration: 151.308768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:20.307387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.0164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:06:20.307467Z","caller":"traceutil/trace.go:171","msg":"trace[1911845151] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2778; }","duration":"170.166091ms","start":"2026-04-22T06:06:20.137286Z","end":"2026-04-22T06:06:20.307452Z","steps":["trace[1911845151] 'count revisions from in-memory index tree' (duration: 169.919479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:20.307826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.445133ms","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-22T06:06:20.307855Z","caller":"traceutil/trace.go:171","msg":"trace[2139174232] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2778; }","duration":"155.515024ms","start":"2026-04-22T06:06:20.152333Z","end":"2026-04-22T06:06:20.307848Z","steps":["trace[2139174232] 'count revisions from in-memory index tree' (duration: 155.375793ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:20.308516Z","caller":"traceutil/trace.go:171","msg":"trace[727775097] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"124.626794ms","start":"2026-04-22T06:06:20.183861Z","end":"2026-04-22T06:06:20.308488Z","steps":["trace[727775097] 'process raft request' (duration: 69.57374ms)","trace[727775097] 'compare' (duration: 54.807572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:55.515399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.042783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:55.515484Z","caller":"traceutil/trace.go:171","msg":"trace[668509234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2969; }","duration":"127.170656ms","start":"2026-04-22T06:06:55.388295Z","end":"2026-04-22T06:06:55.515466Z","steps":["trace[668509234] 'range keys from in-memory index tree' (duration: 126.966192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:55.515906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.201462ms","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-22T06:06:55.516001Z","caller":"traceutil/trace.go:171","msg":"trace[445795154] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2969; }","duration":"117.345644ms","start":"2026-04-22T06:06:55.398633Z","end":"2026-04-22T06:06:55.515979Z","steps":["trace[445795154] 'range keys from in-memory index tree' (duration: 117.106971ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:57.26695Z","caller":"traceutil/trace.go:171","msg":"trace[1049714804] transaction","detail":"{read_only:false; response_revision:2975; number_of_response:1; }","duration":"110.785455ms","start":"2026-04-22T06:06:57.156128Z","end":"2026-04-22T06:06:57.266913Z","steps":["trace[1049714804] 'process raft request' (duration: 69.579217ms)","trace[1049714804] 'compare' (duration: 40.848743ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:57.267089Z","caller":"traceutil/trace.go:171","msg":"trace[716698733] transaction","detail":"{read_only:false; response_revision:2976; number_of_response:1; }","duration":"107.540465ms","start":"2026-04-22T06:06:57.159524Z","end":"2026-04-22T06:06:57.267064Z","steps":["trace[716698733] 'process raft request' (duration: 107.1965ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:09.733014Z","caller":"traceutil/trace.go:171","msg":"trace[1576542808] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"378.30471ms","start":"2026-04-22T06:07:09.354669Z","end":"2026-04-22T06:07:09.732974Z","steps":["trace[1576542808] 'process raft request' (duration: 377.981384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.733331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.518198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T06:07:09.733304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.300084ms","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-22T06:07:09.733369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.073738ms","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-22T06:07:09.733416Z","caller":"traceutil/trace.go:171","msg":"trace[1066094068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3038; }","duration":"360.698282ms","start":"2026-04-22T06:07:09.372697Z","end":"2026-04-22T06:07:09.733395Z","steps":["trace[1066094068] 'agreement among raft nodes before linearized reading' (duration: 360.405957ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:09.733434Z","caller":"traceutil/trace.go:171","msg":"trace[1531372809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3038; }","duration":"361.161149ms","start":"2026-04-22T06:07:09.372259Z","end":"2026-04-22T06:07:09.73342Z","steps":["trace[1531372809] 'agreement among raft nodes before linearized reading' (duration: 360.934035ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:09.733453Z","caller":"traceutil/trace.go:171","msg":"trace[1474806715] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3038; }","duration":"360.587709ms","start":"2026-04-22T06:07:09.372843Z","end":"2026-04-22T06:07:09.733431Z","steps":["trace[1474806715] 'agreement among raft nodes before linearized reading' (duration: 360.161622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.733465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:09.372655Z","time spent":"360.797443ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:07:09.733515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:09.372829Z","time spent":"360.674442ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:07:09.73353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:09.372247Z","time spent":"361.22893ms","remote":"127.0.0.1:44016","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-22T06:07:09.733806Z","caller":"traceutil/trace.go:171","msg":"trace[2048906686] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"331.37942ms","start":"2026-04-22T06:07:09.4024Z","end":"2026-04-22T06:07:09.73378Z","steps":["trace[2048906686] 'process raft request' (duration: 331.233238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.733963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:09.402375Z","time spent":"331.499743ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:09.732931Z","caller":"traceutil/trace.go:171","msg":"trace[477679138] linearizableReadLoop","detail":"{readStateIndex:3155; appliedIndex:3154; }","duration":"335.803327ms","start":"2026-04-22T06:07:09.397091Z","end":"2026-04-22T06:07:09.732894Z","steps":["trace[477679138] 'read index received' (duration: 335.517372ms)","trace[477679138] 'applied index is now lower than readState.Index' (duration: 284.105µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:09.734034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:09.354641Z","time spent":"378.495283ms","remote":"127.0.0.1:44016","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-22T06:07:09.73422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.18304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:09.734483Z","caller":"traceutil/trace.go:171","msg":"trace[376140187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3039; }","duration":"195.517716ms","start":"2026-04-22T06:07:09.538945Z","end":"2026-04-22T06:07:09.734463Z","steps":["trace[376140187] 'agreement among raft nodes before linearized reading' (duration: 195.1827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.734268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.640927ms","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-22T06:07:09.734597Z","caller":"traceutil/trace.go:171","msg":"trace[1582631245] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3039; }","duration":"181.017603ms","start":"2026-04-22T06:07:09.553555Z","end":"2026-04-22T06:07:09.734572Z","steps":["trace[1582631245] 'agreement among raft nodes before linearized reading' (duration: 180.600476ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:09.966008Z","caller":"traceutil/trace.go:171","msg":"trace[1041044200] linearizableReadLoop","detail":"{readStateIndex:3160; appliedIndex:3156; }","duration":"190.259913ms","start":"2026-04-22T06:07:09.775721Z","end":"2026-04-22T06:07:09.965981Z","steps":["trace[1041044200] 'read index received' (duration: 13.676708ms)","trace[1041044200] 'applied index is now lower than readState.Index' (duration: 176.581685ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:09.966162Z","caller":"traceutil/trace.go:171","msg":"trace[320155290] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"226.596679ms","start":"2026-04-22T06:07:09.739556Z","end":"2026-04-22T06:07:09.966153Z","steps":["trace[320155290] 'process raft request' (duration: 226.363414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.966408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.662062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:09.96607Z","caller":"traceutil/trace.go:171","msg":"trace[1138950217] transaction","detail":"{read_only:false; response_revision:3041; number_of_response:1; }","duration":"226.66137ms","start":"2026-04-22T06:07:09.739382Z","end":"2026-04-22T06:07:09.966044Z","steps":["trace[1138950217] 'process raft request' (duration: 152.27912ms)","trace[1138950217] 'compare' (duration: 73.971912ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:09.96645Z","caller":"traceutil/trace.go:171","msg":"trace[2139353607] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3043; }","duration":"190.750933ms","start":"2026-04-22T06:07:09.77569Z","end":"2026-04-22T06:07:09.966441Z","steps":["trace[2139353607] 'agreement among raft nodes before linearized reading' (duration: 190.665461ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:09.966089Z","caller":"traceutil/trace.go:171","msg":"trace[1092796639] transaction","detail":"{read_only:false; response_revision:3042; number_of_response:1; }","duration":"226.551678ms","start":"2026-04-22T06:07:09.739509Z","end":"2026-04-22T06:07:09.966061Z","steps":["trace[1092796639] 'process raft request' (duration: 226.321433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:09.96667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.04746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:09.966752Z","caller":"traceutil/trace.go:171","msg":"trace[398624015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3043; }","duration":"107.130842ms","start":"2026-04-22T06:07:09.859589Z","end":"2026-04-22T06:07:09.96672Z","steps":["trace[398624015] 'agreement among raft nodes before linearized reading' (duration: 107.01431ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:11.522267Z","caller":"traceutil/trace.go:171","msg":"trace[1190120431] transaction","detail":"{read_only:false; response_revision:3050; number_of_response:1; }","duration":"119.851976ms","start":"2026-04-22T06:07:11.402383Z","end":"2026-04-22T06:07:11.522235Z","steps":["trace[1190120431] 'process raft request' (duration: 96.766417ms)","trace[1190120431] 'compare' (duration: 22.896136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:44.496219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.201657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-04-22T06:07:44.49631Z","caller":"traceutil/trace.go:171","msg":"trace[553170369] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3223; }","duration":"197.31052ms","start":"2026-04-22T06:07:44.298981Z","end":"2026-04-22T06:07:44.496292Z","steps":["trace[553170369] 'range keys from in-memory index tree' (duration: 196.938162ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:09.452414Z","caller":"traceutil/trace.go:171","msg":"trace[1008503090] transaction","detail":"{read_only:false; response_revision:3432; number_of_response:1; }","duration":"173.531332ms","start":"2026-04-22T06:08:09.278858Z","end":"2026-04-22T06:08:09.45239Z","steps":["trace[1008503090] 'process raft request' (duration: 173.358408ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:09.565989Z","caller":"traceutil/trace.go:171","msg":"trace[1960491444] transaction","detail":"{read_only:false; response_revision:3433; number_of_response:1; }","duration":"106.290009ms","start":"2026-04-22T06:08:09.45967Z","end":"2026-04-22T06:08:09.56596Z","steps":["trace[1960491444] 'process raft request' (duration: 85.111291ms)","trace[1960491444] 'compare' (duration: 20.638295ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:22.701366Z","caller":"traceutil/trace.go:171","msg":"trace[679990750] transaction","detail":"{read_only:false; response_revision:3510; number_of_response:1; }","duration":"104.113227ms","start":"2026-04-22T06:08:22.597203Z","end":"2026-04-22T06:08:22.701316Z","steps":["trace[679990750] 'process raft request' (duration: 44.624417ms)","trace[679990750] 'compare' (duration: 59.368517ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:39.098706Z","caller":"traceutil/trace.go:171","msg":"trace[1249889737] transaction","detail":"{read_only:false; response_revision:3600; number_of_response:1; }","duration":"180.650397ms","start":"2026-04-22T06:08:38.917848Z","end":"2026-04-22T06:08:39.098498Z","steps":["trace[1249889737] 'process raft request' (duration: 180.504684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:44.346949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.253313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:08:44.347069Z","caller":"traceutil/trace.go:171","msg":"trace[1433372355] linearizableReadLoop","detail":"{readStateIndex:3767; appliedIndex:3766; }","duration":"255.916443ms","start":"2026-04-22T06:08:44.091134Z","end":"2026-04-22T06:08:44.34705Z","steps":["trace[1433372355] 'read index received' (duration: 137.555087ms)","trace[1433372355] 'applied index is now lower than readState.Index' (duration: 118.360346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:44.34717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.038597ms","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-22T06:08:44.347208Z","caller":"traceutil/trace.go:171","msg":"trace[1890297787] 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:3627; }","duration":"256.11984ms","start":"2026-04-22T06:08:44.091077Z","end":"2026-04-22T06:08:44.347197Z","steps":["trace[1890297787] 'agreement among raft nodes before linearized reading' (duration: 256.022447ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:44.347615Z","caller":"traceutil/trace.go:171","msg":"trace[1543061937] transaction","detail":"{read_only:false; response_revision:3627; number_of_response:1; }","duration":"385.194318ms","start":"2026-04-22T06:08:43.962404Z","end":"2026-04-22T06:08:44.347599Z","steps":["trace[1543061937] 'process raft request' (duration: 266.176604ms)","trace[1543061937] 'compare' (duration: 118.14359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:44.347691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:43.962376Z","time spent":"385.27698ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:44.410025Z","caller":"traceutil/trace.go:171","msg":"trace[204661366] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"217.982535ms","start":"2026-04-22T06:08:44.191999Z","end":"2026-04-22T06:08:44.409981Z","steps":["trace[204661366] 'process raft request' (duration: 217.821481ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:46.154944Z","caller":"traceutil/trace.go:171","msg":"trace[1439587474] transaction","detail":"{read_only:false; response_revision:3636; number_of_response:1; }","duration":"141.775418ms","start":"2026-04-22T06:08:46.013107Z","end":"2026-04-22T06:08:46.154883Z","steps":["trace[1439587474] 'process raft request' (duration: 63.807255ms)","trace[1439587474] 'compare' (duration: 77.788268ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:46.500015Z","caller":"traceutil/trace.go:171","msg":"trace[467872250] transaction","detail":"{read_only:false; response_revision:3639; number_of_response:1; }","duration":"147.45601ms","start":"2026-04-22T06:08:46.352522Z","end":"2026-04-22T06:08:46.499978Z","steps":["trace[467872250] 'process raft request' (duration: 83.46443ms)","trace[467872250] 'compare' (duration: 63.846186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:46.835542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.099796ms","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-22T06:08:46.835634Z","caller":"traceutil/trace.go:171","msg":"trace[2099843510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3641; }","duration":"200.27664ms","start":"2026-04-22T06:08:46.635334Z","end":"2026-04-22T06:08:46.835611Z","steps":["trace[2099843510] 'range keys from in-memory index tree' (duration: 199.885739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:48.293391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.470634ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:08:48.297619Z","caller":"traceutil/trace.go:171","msg":"trace[439069976] transaction","detail":"{read_only:false; response_revision:3647; number_of_response:1; }","duration":"295.031809ms","start":"2026-04-22T06:08:48.002553Z","end":"2026-04-22T06:08:48.297585Z","steps":["trace[439069976] 'process raft request' (duration: 294.846434ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:25.174982Z","caller":"traceutil/trace.go:171","msg":"trace[513460951] linearizableReadLoop","detail":"{readStateIndex:4068; appliedIndex:4067; }","duration":"119.311612ms","start":"2026-04-22T06:09:25.055639Z","end":"2026-04-22T06:09:25.17495Z","steps":["trace[513460951] 'read index received' (duration: 118.90101ms)","trace[513460951] 'applied index is now lower than readState.Index' (duration: 409.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:25.175078Z","caller":"traceutil/trace.go:171","msg":"trace[2036932524] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"121.892077ms","start":"2026-04-22T06:09:25.053154Z","end":"2026-04-22T06:09:25.175046Z","steps":["trace[2036932524] 'process raft request' (duration: 121.372622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:25.175257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.543819ms","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-22T06:09:25.175754Z","caller":"traceutil/trace.go:171","msg":"trace[2144742419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3916; }","duration":"119.966841ms","start":"2026-04-22T06:09:25.055606Z","end":"2026-04-22T06:09:25.175573Z","steps":["trace[2144742419] 'agreement among raft nodes before linearized reading' (duration: 119.474757ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:27.042785Z","caller":"traceutil/trace.go:171","msg":"trace[101726050] linearizableReadLoop","detail":"{readStateIndex:4079; appliedIndex:4077; }","duration":"152.291662ms","start":"2026-04-22T06:09:26.890468Z","end":"2026-04-22T06:09:27.04276Z","steps":["trace[101726050] 'read index received' (duration: 67.765518ms)","trace[101726050] 'applied index is now lower than readState.Index' (duration: 84.524934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:09:27.043034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.54939ms","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-22T06:09:27.04309Z","caller":"traceutil/trace.go:171","msg":"trace[1938529501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3927; }","duration":"152.646863ms","start":"2026-04-22T06:09:26.890425Z","end":"2026-04-22T06:09:27.043072Z","steps":["trace[1938529501] 'agreement among raft nodes before linearized reading' (duration: 152.458746ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:27.043325Z","caller":"traceutil/trace.go:171","msg":"trace[1983040099] transaction","detail":"{read_only:false; response_revision:3927; number_of_response:1; }","duration":"178.580031ms","start":"2026-04-22T06:09:26.864733Z","end":"2026-04-22T06:09:27.043313Z","steps":["trace[1983040099] 'process raft request' (duration: 177.949662ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:27.043441Z","caller":"traceutil/trace.go:171","msg":"trace[1177172960] transaction","detail":"{read_only:false; response_revision:3926; number_of_response:1; }","duration":"231.80142ms","start":"2026-04-22T06:09:26.811597Z","end":"2026-04-22T06:09:27.043399Z","steps":["trace[1177172960] 'process raft request' (duration: 146.629527ms)","trace[1177172960] 'compare' (duration: 84.303967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:09:45.015201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.212377ms","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-22T06:09:45.015313Z","caller":"traceutil/trace.go:171","msg":"trace[849092963] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4020; }","duration":"187.386411ms","start":"2026-04-22T06:09:44.827908Z","end":"2026-04-22T06:09:45.015294Z","steps":["trace[849092963] 'count revisions from in-memory index tree' (duration: 187.087293ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:55.56401Z","caller":"traceutil/trace.go:171","msg":"trace[2030363659] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"127.940731ms","start":"2026-04-22T06:09:55.436009Z","end":"2026-04-22T06:09:55.56395Z","steps":["trace[2030363659] 'process raft request' (duration: 127.460177ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:56.796266Z","caller":"traceutil/trace.go:171","msg":"trace[1056191758] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"211.152233ms","start":"2026-04-22T06:09:56.585075Z","end":"2026-04-22T06:09:56.796227Z","steps":["trace[1056191758] 'process raft request' (duration: 203.445021ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:56.798264Z","caller":"traceutil/trace.go:171","msg":"trace[413786968] linearizableReadLoop","detail":"{readStateIndex:4629; appliedIndex:4626; }","duration":"108.627454ms","start":"2026-04-22T06:09:56.689618Z","end":"2026-04-22T06:09:56.798246Z","steps":["trace[413786968] 'read index received' (duration: 99.000484ms)","trace[413786968] 'applied index is now lower than readState.Index' (duration: 9.62627ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:56.798294Z","caller":"traceutil/trace.go:171","msg":"trace[2054365063] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"151.735543ms","start":"2026-04-22T06:09:56.646546Z","end":"2026-04-22T06:09:56.798281Z","steps":["trace[2054365063] 'process raft request' (duration: 151.567688ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:56.798349Z","caller":"traceutil/trace.go:171","msg":"trace[1326317800] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"112.722557ms","start":"2026-04-22T06:09:56.68562Z","end":"2026-04-22T06:09:56.798342Z","steps":["trace[1326317800] 'process raft request' (duration: 112.587973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:56.798688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.044546ms","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-22T06:09:56.798771Z","caller":"traceutil/trace.go:171","msg":"trace[37992771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4453; }","duration":"109.17663ms","start":"2026-04-22T06:09:56.689576Z","end":"2026-04-22T06:09:56.798752Z","steps":["trace[37992771] 'agreement among raft nodes before linearized reading' (duration: 108.929803ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:59.203831Z","caller":"traceutil/trace.go:171","msg":"trace[1851754339] linearizableReadLoop","detail":"{readStateIndex:4721; appliedIndex:4720; }","duration":"236.289126ms","start":"2026-04-22T06:09:58.967523Z","end":"2026-04-22T06:09:59.203812Z","steps":["trace[1851754339] 'read index received' (duration: 158.486452ms)","trace[1851754339] 'applied index is now lower than readState.Index' (duration: 77.801724ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:59.204014Z","caller":"traceutil/trace.go:171","msg":"trace[575684844] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"361.748555ms","start":"2026-04-22T06:09:58.84225Z","end":"2026-04-22T06:09:59.203999Z","steps":["trace[575684844] 'process raft request' (duration: 283.733675ms)","trace[575684844] 'compare' (duration: 77.696091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:09:59.20421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:58.842236Z","time spent":"361.845358ms","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15284,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:09:59.204494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.893664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:09:59.204695Z","caller":"traceutil/trace.go:171","msg":"trace[1780419743] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4544; }","duration":"237.197843ms","start":"2026-04-22T06:09:58.967478Z","end":"2026-04-22T06:09:59.204676Z","steps":["trace[1780419743] 'agreement among raft nodes before linearized reading' (duration: 236.704118ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:59.236603Z","caller":"traceutil/trace.go:171","msg":"trace[2087571848] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"119.03296ms","start":"2026-04-22T06:09:59.117544Z","end":"2026-04-22T06:09:59.236577Z","steps":["trace[2087571848] 'process raft request' (duration: 118.874975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:59.252426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.12676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:09:59.252534Z","caller":"traceutil/trace.go:171","msg":"trace[53388364] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4546; }","duration":"145.276794ms","start":"2026-04-22T06:09:59.107237Z","end":"2026-04-22T06:09:59.252514Z","steps":["trace[53388364] 'agreement among raft nodes before linearized reading' (duration: 145.11422ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:59.408217Z","caller":"traceutil/trace.go:171","msg":"trace[1379735901] linearizableReadLoop","detail":"{readStateIndex:4724; appliedIndex:4723; }","duration":"101.589453ms","start":"2026-04-22T06:09:59.306607Z","end":"2026-04-22T06:09:59.408196Z","steps":["trace[1379735901] 'read index received' (duration: 101.420588ms)","trace[1379735901] 'applied index is now lower than readState.Index' (duration: 168.135µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:59.408569Z","caller":"traceutil/trace.go:171","msg":"trace[1619057392] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"142.046066ms","start":"2026-04-22T06:09:59.266514Z","end":"2026-04-22T06:09:59.40856Z","steps":["trace[1619057392] 'process raft request' (duration: 141.571902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:59.408978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.341456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d984053-b6c8-438a-bea9-afb1473edda6\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-22T06:09:59.409012Z","caller":"traceutil/trace.go:171","msg":"trace[1515938121] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d984053-b6c8-438a-bea9-afb1473edda6; range_end:; response_count:1; response_revision:4547; }","duration":"102.437199ms","start":"2026-04-22T06:09:59.306567Z","end":"2026-04-22T06:09:59.409004Z","steps":["trace[1515938121] 'agreement among raft nodes before linearized reading' (duration: 102.290184ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.807823Z","caller":"traceutil/trace.go:171","msg":"trace[1428480739] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"554.755185ms","start":"2026-04-22T06:10:05.253038Z","end":"2026-04-22T06:10:05.807794Z","steps":["trace[1428480739] 'process raft request' (duration: 518.46949ms)","trace[1428480739] 'compare' (duration: 35.832742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:05.807998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:05.253016Z","time spent":"554.890069ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:05.844032Z","caller":"traceutil/trace.go:171","msg":"trace[1368892016] linearizableReadLoop","detail":"{readStateIndex:4859; appliedIndex:4857; }","duration":"464.144426ms","start":"2026-04-22T06:10:05.379862Z","end":"2026-04-22T06:10:05.844006Z","steps":["trace[1368892016] 'read index received' (duration: 391.640168ms)","trace[1368892016] 'applied index is now lower than readState.Index' (duration: 72.503018ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:05.844331Z","caller":"traceutil/trace.go:171","msg":"trace[425500759] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"575.069534ms","start":"2026-04-22T06:10:05.269248Z","end":"2026-04-22T06:10:05.844317Z","steps":["trace[425500759] 'process raft request' (duration: 574.63517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.844472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:05.269221Z","time spent":"575.152536ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:05.844541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.164927ms","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-22T06:10:05.844622Z","caller":"traceutil/trace.go:171","msg":"trace[732938193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4676; }","duration":"400.280361ms","start":"2026-04-22T06:10:05.444323Z","end":"2026-04-22T06:10:05.844603Z","steps":["trace[732938193] 'agreement among raft nodes before linearized reading' (duration: 400.057864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.84464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.845222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T06:10:05.844728Z","caller":"traceutil/trace.go:171","msg":"trace[1806742504] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4676; }","duration":"213.961056ms","start":"2026-04-22T06:10:05.630743Z","end":"2026-04-22T06:10:05.844704Z","steps":["trace[1806742504] 'agreement among raft nodes before linearized reading' (duration: 213.655777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.844756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.06724ms","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-22T06:10:05.844804Z","caller":"traceutil/trace.go:171","msg":"trace[319667967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4676; }","duration":"217.097221ms","start":"2026-04-22T06:10:05.627679Z","end":"2026-04-22T06:10:05.844776Z","steps":["trace[319667967] 'agreement among raft nodes before linearized reading' (duration: 217.016109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.844804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.93549ms","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-22T06:10:05.844846Z","caller":"traceutil/trace.go:171","msg":"trace[2064097921] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4676; }","duration":"465.015792ms","start":"2026-04-22T06:10:05.379819Z","end":"2026-04-22T06:10:05.844834Z","steps":["trace[2064097921] 'agreement among raft nodes before linearized reading' (duration: 464.94498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.844878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:05.379794Z","time spent":"465.075464ms","remote":"127.0.0.1:44244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:10:05.845003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.862385ms","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-22T06:10:05.845038Z","caller":"traceutil/trace.go:171","msg":"trace[566549537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4676; }","duration":"218.897947ms","start":"2026-04-22T06:10:05.626132Z","end":"2026-04-22T06:10:05.84503Z","steps":["trace[566549537] 'agreement among raft nodes before linearized reading' (duration: 218.771993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.845136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.020057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:10:05.845176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.310069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:10:05.844668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:05.444311Z","time spent":"400.348393ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:10:05.845188Z","caller":"traceutil/trace.go:171","msg":"trace[175706087] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4676; }","duration":"125.10553ms","start":"2026-04-22T06:10:05.720067Z","end":"2026-04-22T06:10:05.845173Z","steps":["trace[175706087] 'agreement among raft nodes before linearized reading' (duration: 125.018818ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.845208Z","caller":"traceutil/trace.go:171","msg":"trace[128552492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4676; }","duration":"307.368751ms","start":"2026-04-22T06:10:05.53783Z","end":"2026-04-22T06:10:05.845199Z","steps":["trace[128552492] 'agreement among raft nodes before linearized reading' (duration: 307.31994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.845489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:05.537814Z","time spent":"307.663559ms","remote":"127.0.0.1:43748","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-22T06:10:05.989842Z","caller":"traceutil/trace.go:171","msg":"trace[1993041049] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"140.150558ms","start":"2026-04-22T06:10:05.849673Z","end":"2026-04-22T06:10:05.989824Z","steps":["trace[1993041049] 'process raft request' (duration: 66.053352ms)","trace[1993041049] 'compare' (duration: 74.002823ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:05.99581Z","caller":"traceutil/trace.go:171","msg":"trace[1783366820] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"146.002426ms","start":"2026-04-22T06:10:05.849769Z","end":"2026-04-22T06:10:05.995771Z","steps":["trace[1783366820] 'process raft request' (duration: 145.77826ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.995818Z","caller":"traceutil/trace.go:171","msg":"trace[399253477] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"145.44791ms","start":"2026-04-22T06:10:05.850339Z","end":"2026-04-22T06:10:05.995787Z","steps":["trace[399253477] 'process raft request' (duration: 145.367447ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.996023Z","caller":"traceutil/trace.go:171","msg":"trace[1205581177] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4859; }","duration":"133.905507ms","start":"2026-04-22T06:10:05.862106Z","end":"2026-04-22T06:10:05.996011Z","steps":["trace[1205581177] 'read index received' (duration: 53.608723ms)","trace[1205581177] 'applied index is now lower than readState.Index' (duration: 80.295984ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:05.9961Z","caller":"traceutil/trace.go:171","msg":"trace[702260317] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"133.31949ms","start":"2026-04-22T06:10:05.862773Z","end":"2026-04-22T06:10:05.996093Z","steps":["trace[702260317] 'process raft request' (duration: 132.970119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:05.996259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.131984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-sw5tj\" ","response":"range_response_count:1 size:9712"} {"level":"warn","ts":"2026-04-22T06:10:05.996313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.717511ms","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-22T06:10:05.99633Z","caller":"traceutil/trace.go:171","msg":"trace[1321701559] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-sw5tj; range_end:; response_count:1; response_revision:4682; }","duration":"134.217427ms","start":"2026-04-22T06:10:05.862099Z","end":"2026-04-22T06:10:05.996316Z","steps":["trace[1321701559] 'agreement among raft nodes before linearized reading' (duration: 134.031821ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.99634Z","caller":"traceutil/trace.go:171","msg":"trace[1530251554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4682; }","duration":"109.784273ms","start":"2026-04-22T06:10:05.886549Z","end":"2026-04-22T06:10:05.996333Z","steps":["trace[1530251554] 'agreement among raft nodes before linearized reading' (duration: 109.67264ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:05.995834Z","caller":"traceutil/trace.go:171","msg":"trace[712590657] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"145.591914ms","start":"2026-04-22T06:10:05.85023Z","end":"2026-04-22T06:10:05.995822Z","steps":["trace[712590657] 'process raft request' (duration: 145.437199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:06.391159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.093957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:10:06.391474Z","caller":"traceutil/trace.go:171","msg":"trace[84096472] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"281.596416ms","start":"2026-04-22T06:10:06.109838Z","end":"2026-04-22T06:10:06.391434Z","steps":["trace[84096472] 'process raft request' (duration: 281.487972ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:06.391734Z","caller":"traceutil/trace.go:171","msg":"trace[1029353674] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"385.489414ms","start":"2026-04-22T06:10:06.006204Z","end":"2026-04-22T06:10:06.391693Z","steps":["trace[1029353674] 'process raft request' (duration: 88.779117ms)","trace[1029353674] 'compare' (duration: 295.658244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:06.39201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:06.006183Z","time spent":"385.70089ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:11.90082Z","caller":"traceutil/trace.go:171","msg":"trace[165741135] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4964; }","duration":"239.11985ms","start":"2026-04-22T06:10:11.661675Z","end":"2026-04-22T06:10:11.900795Z","steps":["trace[165741135] 'read index received' (duration: 238.922064ms)","trace[165741135] 'applied index is now lower than readState.Index' (duration: 196.476µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:11.900837Z","caller":"traceutil/trace.go:171","msg":"trace[1433795897] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"294.012353ms","start":"2026-04-22T06:10:11.6068Z","end":"2026-04-22T06:10:11.900812Z","steps":["trace[1433795897] 'process raft request' (duration: 293.819267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:11.901071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.380137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-22T06:10:11.901114Z","caller":"traceutil/trace.go:171","msg":"trace[893724702] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0; range_end:; response_count:1; response_revision:4780; }","duration":"239.4776ms","start":"2026-04-22T06:10:11.661625Z","end":"2026-04-22T06:10:11.901103Z","steps":["trace[893724702] 'agreement among raft nodes before linearized reading' (duration: 239.263424ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:12.343704Z","caller":"traceutil/trace.go:171","msg":"trace[1594536459] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4965; }","duration":"426.231668ms","start":"2026-04-22T06:10:11.917451Z","end":"2026-04-22T06:10:12.343683Z","steps":["trace[1594536459] 'read index received' (duration: 426.046972ms)","trace[1594536459] 'applied index is now lower than readState.Index' (duration: 183.606µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:12.34403Z","caller":"traceutil/trace.go:171","msg":"trace[645215124] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"483.420201ms","start":"2026-04-22T06:10:11.86058Z","end":"2026-04-22T06:10:12.344001Z","steps":["trace[645215124] 'process raft request' (duration: 483.046929ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:12.344146Z","caller":"traceutil/trace.go:171","msg":"trace[705898196] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"518.795055ms","start":"2026-04-22T06:10:11.825339Z","end":"2026-04-22T06:10:12.344134Z","steps":["trace[705898196] 'process raft request' (duration: 517.954809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.344215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.860566Z","time spent":"483.533234ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:12.344248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.825321Z","time spent":"518.866177ms","remote":"127.0.0.1:44016","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-22T06:10:12.344055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.581988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:10:12.344377Z","caller":"traceutil/trace.go:171","msg":"trace[1285816326] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4782; }","duration":"426.952811ms","start":"2026-04-22T06:10:11.917414Z","end":"2026-04-22T06:10:12.344366Z","steps":["trace[1285816326] 'agreement among raft nodes before linearized reading' (duration: 426.586039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.344414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.917397Z","time spent":"427.007762ms","remote":"127.0.0.1:41298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":8,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:10:12.446006Z","caller":"traceutil/trace.go:171","msg":"trace[2107949024] linearizableReadLoop","detail":"{readStateIndex:4968; appliedIndex:4967; }","duration":"102.191137ms","start":"2026-04-22T06:10:12.343785Z","end":"2026-04-22T06:10:12.445976Z","steps":["trace[2107949024] 'read index received' (duration: 71.00469ms)","trace[2107949024] 'applied index is now lower than readState.Index' (duration: 31.185087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:12.446263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.824237ms","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-22T06:10:12.44633Z","caller":"traceutil/trace.go:171","msg":"trace[1695001040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4783; }","duration":"423.92953ms","start":"2026-04-22T06:10:12.022383Z","end":"2026-04-22T06:10:12.446313Z","steps":["trace[1695001040] 'agreement among raft nodes before linearized reading' (duration: 423.657662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.855346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T06:10:12.446334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.902579ms","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-22T06:10:12.446376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.022366Z","time spent":"424.001031ms","remote":"127.0.0.1:44016","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":"info","ts":"2026-04-22T06:10:12.446393Z","caller":"traceutil/trace.go:171","msg":"trace[1627386868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4783; }","duration":"435.97722ms","start":"2026-04-22T06:10:12.010395Z","end":"2026-04-22T06:10:12.446373Z","steps":["trace[1627386868] 'agreement among raft nodes before linearized reading' (duration: 435.67486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.122415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-04-22T06:10:12.446407Z","caller":"traceutil/trace.go:171","msg":"trace[384749930] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4783; }","duration":"423.995631ms","start":"2026-04-22T06:10:12.022395Z","end":"2026-04-22T06:10:12.446391Z","steps":["trace[384749930] 'agreement among raft nodes before linearized reading' (duration: 423.821106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.022389Z","time spent":"424.113776ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:10:12.446529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.49653ms","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-22T06:10:12.44649Z","caller":"traceutil/trace.go:171","msg":"trace[1328279750] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4783; }","duration":"528.341942ms","start":"2026-04-22T06:10:11.918131Z","end":"2026-04-22T06:10:12.446473Z","steps":["trace[1328279750] 'agreement among raft nodes before linearized reading' (duration: 528.018492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.505145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:10:12.446656Z","caller":"traceutil/trace.go:171","msg":"trace[820929525] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4783; }","duration":"114.582657ms","start":"2026-04-22T06:10:12.332062Z","end":"2026-04-22T06:10:12.446644Z","steps":["trace[820929525] 'agreement among raft nodes before linearized reading' (duration: 114.524345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.185441ms","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-22T06:10:12.446745Z","caller":"traceutil/trace.go:171","msg":"trace[1136086607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4783; }","duration":"414.237033ms","start":"2026-04-22T06:10:12.032496Z","end":"2026-04-22T06:10:12.446733Z","steps":["trace[1136086607] 'agreement among raft nodes before linearized reading' (duration: 414.13641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.446778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.032491Z","time spent":"414.279944ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:10:12.446757Z","caller":"traceutil/trace.go:171","msg":"trace[330641900] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"427.815029ms","start":"2026-04-22T06:10:12.0189Z","end":"2026-04-22T06:10:12.446715Z","steps":["trace[330641900] 'process raft request' (duration: 395.917391ms)","trace[330641900] 'compare' (duration: 31.004651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:12.44693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.018886Z","time spent":"427.980715ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:12.44662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.918121Z","time spent":"528.483506ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12995,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-22T06:10:12.446557Z","caller":"traceutil/trace.go:171","msg":"trace[639759557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4783; }","duration":"427.574222ms","start":"2026-04-22T06:10:12.018974Z","end":"2026-04-22T06:10:12.446549Z","steps":["trace[639759557] 'agreement among raft nodes before linearized reading' (duration: 427.49334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.447121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.018945Z","time spent":"428.16094ms","remote":"127.0.0.1:44016","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-22T06:10:12.446446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.010378Z","time spent":"436.059113ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:10:12.447534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.719872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-544c848b8-82fhg.18a898f050650e40\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-04-22T06:10:12.447589Z","caller":"traceutil/trace.go:171","msg":"trace[867992989] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-544c848b8-82fhg.18a898f050650e40; range_end:; response_count:1; response_revision:4783; }","duration":"527.800534ms","start":"2026-04-22T06:10:11.919772Z","end":"2026-04-22T06:10:12.447572Z","steps":["trace[867992989] 'agreement among raft nodes before linearized reading' (duration: 526.426332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.447618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.919758Z","time spent":"527.853816ms","remote":"127.0.0.1:43820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":916,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-544c848b8-82fhg.18a898f050650e40\" "} {"level":"warn","ts":"2026-04-22T06:10:12.447842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.042112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-22T06:10:12.447901Z","caller":"traceutil/trace.go:171","msg":"trace[1639811984] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4783; }","duration":"497.104964ms","start":"2026-04-22T06:10:11.95078Z","end":"2026-04-22T06:10:12.447885Z","steps":["trace[1639811984] 'agreement among raft nodes before linearized reading' (duration: 496.960509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:12.447957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:11.950768Z","time spent":"497.176715ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-22T06:10:13.183849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.283283ms","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-22T06:10:13.183919Z","caller":"traceutil/trace.go:171","msg":"trace[1404688023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4800; }","duration":"316.417057ms","start":"2026-04-22T06:10:12.867481Z","end":"2026-04-22T06:10:13.183898Z","steps":["trace[1404688023] 'range keys from in-memory index tree' (duration: 316.120268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:13.184004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:12.867455Z","time spent":"316.540601ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:10:13.184004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.004024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-22T06:10:13.184065Z","caller":"traceutil/trace.go:171","msg":"trace[322788483] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4800; }","duration":"214.074466ms","start":"2026-04-22T06:10:12.969973Z","end":"2026-04-22T06:10:13.184047Z","steps":["trace[322788483] 'range keys from in-memory index tree' (duration: 213.861419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:13.184112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.3976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-04-22T06:10:13.184175Z","caller":"traceutil/trace.go:171","msg":"trace[2123799799] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0; range_end:; response_count:1; response_revision:4800; }","duration":"278.494043ms","start":"2026-04-22T06:10:12.905663Z","end":"2026-04-22T06:10:13.184157Z","steps":["trace[2123799799] 'range keys from in-memory index tree' (duration: 278.219715ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:13.290941Z","caller":"traceutil/trace.go:171","msg":"trace[1941769704] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"102.660493ms","start":"2026-04-22T06:10:13.188257Z","end":"2026-04-22T06:10:13.290917Z","steps":["trace[1941769704] 'process raft request' (duration: 102.510619ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:15.012041Z","caller":"traceutil/trace.go:171","msg":"trace[2014876785] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"497.49354ms","start":"2026-04-22T06:10:14.514524Z","end":"2026-04-22T06:10:15.012018Z","steps":["trace[2014876785] 'process raft request' (duration: 497.333285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.012189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.51451Z","time spent":"497.605833ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:15.093639Z","caller":"traceutil/trace.go:171","msg":"trace[1597553697] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"577.998408ms","start":"2026-04-22T06:10:14.515613Z","end":"2026-04-22T06:10:15.093611Z","steps":["trace[1597553697] 'process raft request' (duration: 577.847053ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:15.093782Z","caller":"traceutil/trace.go:171","msg":"trace[1209601096] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5004; }","duration":"561.742158ms","start":"2026-04-22T06:10:14.532022Z","end":"2026-04-22T06:10:15.093764Z","steps":["trace[1209601096] 'read index received' (duration: 479.906799ms)","trace[1209601096] 'applied index is now lower than readState.Index' (duration: 81.834299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:15.093821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.51559Z","time spent":"578.147391ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:15.094072Z","caller":"traceutil/trace.go:171","msg":"trace[1273090012] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"577.636306ms","start":"2026-04-22T06:10:14.516423Z","end":"2026-04-22T06:10:15.094059Z","steps":["trace[1273090012] 'process raft request' (duration: 577.147851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.094162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.516413Z","time spent":"577.699928ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:15.094219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.198662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-04-22T06:10:15.094256Z","caller":"traceutil/trace.go:171","msg":"trace[383363763] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0; range_end:; response_count:1; response_revision:4824; }","duration":"562.236213ms","start":"2026-04-22T06:10:14.532011Z","end":"2026-04-22T06:10:15.094247Z","steps":["trace[383363763] 'agreement among raft nodes before linearized reading' (duration: 562.12631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.094279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.531978Z","time spent":"562.295465ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3971,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" "} {"level":"warn","ts":"2026-04-22T06:10:15.094302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.854938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:10:15.094062Z","caller":"traceutil/trace.go:171","msg":"trace[219301900] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"579.174664ms","start":"2026-04-22T06:10:14.514497Z","end":"2026-04-22T06:10:15.093671Z","steps":["trace[219301900] 'process raft request' (duration: 578.817683ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:15.094345Z","caller":"traceutil/trace.go:171","msg":"trace[177401531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4824; }","duration":"557.91633ms","start":"2026-04-22T06:10:14.536415Z","end":"2026-04-22T06:10:15.094332Z","steps":["trace[177401531] 'agreement among raft nodes before linearized reading' (duration: 557.854308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.094837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.536405Z","time spent":"558.420936ms","remote":"127.0.0.1:43748","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-22T06:10:15.09439Z","caller":"traceutil/trace.go:171","msg":"trace[310314819] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"577.649667ms","start":"2026-04-22T06:10:14.516732Z","end":"2026-04-22T06:10:15.094382Z","steps":["trace[310314819] 'process raft request' (duration: 576.925365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.095131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.516711Z","time spent":"578.361428ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:15.094415Z","caller":"traceutil/trace.go:171","msg":"trace[1535509358] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"447.121473ms","start":"2026-04-22T06:10:14.647287Z","end":"2026-04-22T06:10:15.094409Z","steps":["trace[1535509358] 'process raft request' (duration: 446.426852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:15.095304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.647275Z","time spent":"447.978329ms","remote":"127.0.0.1:44016","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-22T06:10:15.094655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:14.51448Z","time spent":"580.058131ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:21.339671Z","caller":"traceutil/trace.go:171","msg":"trace[1267309242] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"226.443153ms","start":"2026-04-22T06:10:21.113202Z","end":"2026-04-22T06:10:21.339645Z","steps":["trace[1267309242] 'read index received' (duration: 226.273187ms)","trace[1267309242] 'applied index is now lower than readState.Index' (duration: 168.836µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:21.339792Z","caller":"traceutil/trace.go:171","msg":"trace[813785716] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"302.517789ms","start":"2026-04-22T06:10:21.037263Z","end":"2026-04-22T06:10:21.339781Z","steps":["trace[813785716] 'process raft request' (duration: 302.220099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:21.339923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:21.037247Z","time spent":"302.586521ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:21.34013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.903437ms","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-22T06:10:21.340343Z","caller":"traceutil/trace.go:171","msg":"trace[1079031767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4906; }","duration":"227.145115ms","start":"2026-04-22T06:10:21.113179Z","end":"2026-04-22T06:10:21.340324Z","steps":["trace[1079031767] 'agreement among raft nodes before linearized reading' (duration: 226.701531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:21.34813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.295956ms","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-22T06:10:21.348417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.634494ms","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-22T06:10:21.348475Z","caller":"traceutil/trace.go:171","msg":"trace[590968766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4907; }","duration":"230.739287ms","start":"2026-04-22T06:10:21.117722Z","end":"2026-04-22T06:10:21.348462Z","steps":["trace[590968766] 'agreement among raft nodes before linearized reading' (duration: 230.556871ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:21.348445Z","caller":"traceutil/trace.go:171","msg":"trace[1140991160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4907; }","duration":"234.622187ms","start":"2026-04-22T06:10:21.113812Z","end":"2026-04-22T06:10:21.348435Z","steps":["trace[1140991160] 'agreement among raft nodes before linearized reading' (duration: 234.253746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:21.348487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.154538ms","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-22T06:10:21.348887Z","caller":"traceutil/trace.go:171","msg":"trace[1221223483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4907; }","duration":"229.59859ms","start":"2026-04-22T06:10:21.119274Z","end":"2026-04-22T06:10:21.348872Z","steps":["trace[1221223483] 'agreement among raft nodes before linearized reading' (duration: 228.948201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:21.348363Z","caller":"traceutil/trace.go:171","msg":"trace[1519851178] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"161.713708ms","start":"2026-04-22T06:10:21.18663Z","end":"2026-04-22T06:10:21.348344Z","steps":["trace[1519851178] 'process raft request' (duration: 161.341106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.229457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.525008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T06:10:25.229554Z","caller":"traceutil/trace.go:171","msg":"trace[1304597187] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4934; }","duration":"204.645501ms","start":"2026-04-22T06:10:25.024891Z","end":"2026-04-22T06:10:25.229537Z","steps":["trace[1304597187] 'range keys from in-memory index tree' (duration: 204.308842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.229881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.138497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-04-22T06:10:25.229961Z","caller":"traceutil/trace.go:171","msg":"trace[1701992] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4934; }","duration":"205.263671ms","start":"2026-04-22T06:10:25.024681Z","end":"2026-04-22T06:10:25.229944Z","steps":["trace[1701992] 'range keys from in-memory index tree' (duration: 204.976932ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:25.662607Z","caller":"traceutil/trace.go:171","msg":"trace[1434321292] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"461.754056ms","start":"2026-04-22T06:10:25.200828Z","end":"2026-04-22T06:10:25.662582Z","steps":["trace[1434321292] 'process raft request' (duration: 461.622012ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:25.662626Z","caller":"traceutil/trace.go:171","msg":"trace[1773428958] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5125; }","duration":"429.39313ms","start":"2026-04-22T06:10:25.233189Z","end":"2026-04-22T06:10:25.662583Z","steps":["trace[1773428958] 'read index received' (duration: 429.382569ms)","trace[1773428958] 'applied index is now lower than readState.Index' (duration: 9.051µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:25.662962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.200806Z","time spent":"461.87258ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:25.66311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.897976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-22T06:10:25.663189Z","caller":"traceutil/trace.go:171","msg":"trace[757045913] range","detail":"{range_begin:/registry/persistentvolumes/pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0; range_end:; response_count:1; response_revision:4935; }","duration":"430.020489ms","start":"2026-04-22T06:10:25.233154Z","end":"2026-04-22T06:10:25.663174Z","steps":["trace[757045913] 'agreement among raft nodes before linearized reading' (duration: 429.694809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.663278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.233135Z","time spent":"430.130523ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-37229ef6-0013-4d6f-9f2e-17731b7620d0\" "} {"level":"info","ts":"2026-04-22T06:10:25.892797Z","caller":"traceutil/trace.go:171","msg":"trace[1836496011] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"652.258128ms","start":"2026-04-22T06:10:25.24051Z","end":"2026-04-22T06:10:25.892768Z","steps":["trace[1836496011] 'process raft request' (duration: 650.639278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.892938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.240489Z","time spent":"652.361381ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10666,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:25.893378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.426454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T06:10:25.893388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.261116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T06:10:25.893443Z","caller":"traceutil/trace.go:171","msg":"trace[99588168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4937; }","duration":"525.518528ms","start":"2026-04-22T06:10:25.367907Z","end":"2026-04-22T06:10:25.893426Z","steps":["trace[99588168] 'agreement among raft nodes before linearized reading' (duration: 523.323739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.893495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.3679Z","time spent":"525.587209ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T06:10:25.893492Z","caller":"traceutil/trace.go:171","msg":"trace[1461632687] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4937; }","duration":"541.367359ms","start":"2026-04-22T06:10:25.352074Z","end":"2026-04-22T06:10:25.893442Z","steps":["trace[1461632687] 'agreement among raft nodes before linearized reading' (duration: 540.993328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.89355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.352055Z","time spent":"541.478813ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:10:25.893594Z","caller":"traceutil/trace.go:171","msg":"trace[536507124] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"659.506732ms","start":"2026-04-22T06:10:25.234042Z","end":"2026-04-22T06:10:25.893549Z","steps":["trace[536507124] 'process raft request' (duration: 656.967113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.893791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.23403Z","time spent":"659.666857ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:25.893812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.5917ms","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-22T06:10:25.893931Z","caller":"traceutil/trace.go:171","msg":"trace[980289249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4937; }","duration":"526.728165ms","start":"2026-04-22T06:10:25.367173Z","end":"2026-04-22T06:10:25.893902Z","steps":["trace[980289249] 'agreement among raft nodes before linearized reading' (duration: 526.381045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.893965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.635118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-22T06:10:25.894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.367161Z","time spent":"526.825608ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:10:25.894054Z","caller":"traceutil/trace.go:171","msg":"trace[848463603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4937; }","duration":"531.731511ms","start":"2026-04-22T06:10:25.362286Z","end":"2026-04-22T06:10:25.894017Z","steps":["trace[848463603] 'agreement among raft nodes before linearized reading' (duration: 531.549535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.894158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.135509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:10:25.894181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.532207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-04-22T06:10:25.894191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.362271Z","time spent":"531.901586ms","remote":"127.0.0.1:44016","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-22T06:10:25.894204Z","caller":"traceutil/trace.go:171","msg":"trace[1957970947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4937; }","duration":"262.206981ms","start":"2026-04-22T06:10:25.63198Z","end":"2026-04-22T06:10:25.894187Z","steps":["trace[1957970947] 'agreement among raft nodes before linearized reading' (duration: 261.967654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.893392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.890402ms","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-22T06:10:25.894342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.705843ms","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-22T06:10:25.894366Z","caller":"traceutil/trace.go:171","msg":"trace[2122242904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4937; }","duration":"525.763554ms","start":"2026-04-22T06:10:25.368592Z","end":"2026-04-22T06:10:25.894355Z","steps":["trace[2122242904] 'agreement among raft nodes before linearized reading' (duration: 522.668548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.894392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.368572Z","time spent":"525.815885ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T06:10:25.89438Z","caller":"traceutil/trace.go:171","msg":"trace[2037378955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4937; }","duration":"529.902233ms","start":"2026-04-22T06:10:25.364455Z","end":"2026-04-22T06:10:25.894357Z","steps":["trace[2037378955] 'agreement among raft nodes before linearized reading' (duration: 528.700276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.894465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.364432Z","time spent":"530.012867ms","remote":"127.0.0.1:44016","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-22T06:10:25.894211Z","caller":"traceutil/trace.go:171","msg":"trace[945635199] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4937; }","duration":"525.581029ms","start":"2026-04-22T06:10:25.368622Z","end":"2026-04-22T06:10:25.894203Z","steps":["trace[945635199] 'agreement among raft nodes before linearized reading' (duration: 522.623287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.894708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.085925ms","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-22T06:10:25.894732Z","caller":"traceutil/trace.go:171","msg":"trace[1049170657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4937; }","duration":"296.153967ms","start":"2026-04-22T06:10:25.598572Z","end":"2026-04-22T06:10:25.894726Z","steps":["trace[1049170657] 'agreement among raft nodes before linearized reading' (duration: 292.714199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:25.894725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:25.368614Z","time spent":"526.088115ms","remote":"127.0.0.1:36158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-22T06:10:25.892634Z","caller":"traceutil/trace.go:171","msg":"trace[1207182813] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5125; }","duration":"228.41041ms","start":"2026-04-22T06:10:25.662791Z","end":"2026-04-22T06:10:25.891201Z","steps":["trace[1207182813] 'read index received' (duration: 228.150022ms)","trace[1207182813] 'applied index is now lower than readState.Index' (duration: 259.618µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:26.498151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.050528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:10:26.498243Z","caller":"traceutil/trace.go:171","msg":"trace[2005018622] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4948; }","duration":"247.191622ms","start":"2026-04-22T06:10:26.25103Z","end":"2026-04-22T06:10:26.498221Z","steps":["trace[2005018622] 'count revisions from in-memory index tree' (duration: 246.943134ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:26.541693Z","caller":"traceutil/trace.go:171","msg":"trace[666225152] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"158.431709ms","start":"2026-04-22T06:10:26.383227Z","end":"2026-04-22T06:10:26.541658Z","steps":["trace[666225152] 'process raft request' (duration: 158.204672ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:26.661774Z","caller":"traceutil/trace.go:171","msg":"trace[1205105424] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5138; }","duration":"123.194323ms","start":"2026-04-22T06:10:26.538559Z","end":"2026-04-22T06:10:26.661754Z","steps":["trace[1205105424] 'read index received' (duration: 2.945961ms)","trace[1205105424] 'applied index is now lower than readState.Index' (duration: 120.247482ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:10:26.661829Z","caller":"traceutil/trace.go:171","msg":"trace[68047084] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"271.441907ms","start":"2026-04-22T06:10:26.390331Z","end":"2026-04-22T06:10:26.661772Z","steps":["trace[68047084] 'process raft request' (duration: 242.354362ms)","trace[68047084] 'compare' (duration: 28.863288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:26.661879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.312857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:10:26.661911Z","caller":"traceutil/trace.go:171","msg":"trace[2121545046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"123.38568ms","start":"2026-04-22T06:10:26.538515Z","end":"2026-04-22T06:10:26.6619Z","steps":["trace[2121545046] 'agreement among raft nodes before linearized reading' (duration: 123.325048ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:26.661924Z","caller":"traceutil/trace.go:171","msg":"trace[1435843893] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"172.412284ms","start":"2026-04-22T06:10:26.489388Z","end":"2026-04-22T06:10:26.6618Z","steps":["trace[1435843893] 'process raft request' (duration: 172.300441ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.005367Z","caller":"traceutil/trace.go:171","msg":"trace[240288435] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"250.040067ms","start":"2026-04-22T06:10:27.755307Z","end":"2026-04-22T06:10:28.005347Z","steps":["trace[240288435] 'read index received' (duration: 249.876362ms)","trace[240288435] 'applied index is now lower than readState.Index' (duration: 162.555µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:28.00559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.270804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-04-22T06:10:28.005688Z","caller":"traceutil/trace.go:171","msg":"trace[2090835387] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4952; }","duration":"250.409548ms","start":"2026-04-22T06:10:27.755265Z","end":"2026-04-22T06:10:28.005674Z","steps":["trace[2090835387] 'agreement among raft nodes before linearized reading' (duration: 250.199182ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.005769Z","caller":"traceutil/trace.go:171","msg":"trace[1653844472] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"250.593605ms","start":"2026-04-22T06:10:27.754871Z","end":"2026-04-22T06:10:28.005464Z","steps":["trace[1653844472] 'process raft request' (duration: 250.326357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.512401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392855161321363,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:10:28.76995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.742956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a898f04255c75b\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-04-22T06:10:28.77001Z","caller":"traceutil/trace.go:171","msg":"trace[2011232343] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a898f04255c75b; range_end:; response_count:1; response_revision:4952; }","duration":"760.843399ms","start":"2026-04-22T06:10:28.009149Z","end":"2026-04-22T06:10:28.769993Z","steps":["trace[2011232343] 'range keys from in-memory index tree' (duration: 760.612682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.77004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.009136Z","time spent":"760.8976ms","remote":"127.0.0.1:43820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":763,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18a898f04255c75b\" "} {"level":"info","ts":"2026-04-22T06:10:28.770337Z","caller":"traceutil/trace.go:171","msg":"trace[695214989] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"760.309021ms","start":"2026-04-22T06:10:28.009979Z","end":"2026-04-22T06:10:28.770288Z","steps":["trace[695214989] 'process raft request' (duration: 743.5918ms)","trace[695214989] 'compare' (duration: 16.069281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:28.770494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.009964Z","time spent":"760.447306ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:28.805926Z","caller":"traceutil/trace.go:171","msg":"trace[954042548] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"795.701365ms","start":"2026-04-22T06:10:28.010196Z","end":"2026-04-22T06:10:28.805897Z","steps":["trace[954042548] 'process raft request' (duration: 795.471388ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.806014Z","caller":"traceutil/trace.go:171","msg":"trace[1788252217] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"794.690514ms","start":"2026-04-22T06:10:28.011312Z","end":"2026-04-22T06:10:28.806002Z","steps":["trace[1788252217] 'process raft request' (duration: 794.495848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.806063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.010167Z","time spent":"795.824919ms","remote":"127.0.0.1:44016","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-22T06:10:28.806092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.011289Z","time spent":"794.752416ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:28.805946Z","caller":"traceutil/trace.go:171","msg":"trace[417692402] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"795.616493ms","start":"2026-04-22T06:10:28.010313Z","end":"2026-04-22T06:10:28.80593Z","steps":["trace[417692402] 'process raft request' (duration: 795.456958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.806271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.010302Z","time spent":"795.898171ms","remote":"127.0.0.1:44016","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-22T06:10:28.806508Z","caller":"traceutil/trace.go:171","msg":"trace[772266138] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"794.926171ms","start":"2026-04-22T06:10:28.011557Z","end":"2026-04-22T06:10:28.806483Z","steps":["trace[772266138] 'process raft request' (duration: 794.728165ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.806424Z","caller":"traceutil/trace.go:171","msg":"trace[409752259] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"794.946632ms","start":"2026-04-22T06:10:28.011455Z","end":"2026-04-22T06:10:28.806401Z","steps":["trace[409752259] 'process raft request' (duration: 794.781007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.806696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.011551Z","time spent":"795.021995ms","remote":"127.0.0.1:44016","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-22T06:10:28.807018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.011428Z","time spent":"795.285692ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:28.809075Z","caller":"traceutil/trace.go:171","msg":"trace[22162384] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"263.713981ms","start":"2026-04-22T06:10:28.545337Z","end":"2026-04-22T06:10:28.809051Z","steps":["trace[22162384] 'process raft request' (duration: 263.496064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.809104Z","caller":"traceutil/trace.go:171","msg":"trace[975453515] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"141.910514ms","start":"2026-04-22T06:10:28.667159Z","end":"2026-04-22T06:10:28.809069Z","steps":["trace[975453515] 'process raft request' (duration: 141.786201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.809236Z","caller":"traceutil/trace.go:171","msg":"trace[28089736] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"137.568028ms","start":"2026-04-22T06:10:28.671656Z","end":"2026-04-22T06:10:28.809224Z","steps":["trace[28089736] 'process raft request' (duration: 137.355402ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:28.809305Z","caller":"traceutil/trace.go:171","msg":"trace[724192872] linearizableReadLoop","detail":"{readStateIndex:5149; appliedIndex:5142; }","duration":"797.921164ms","start":"2026-04-22T06:10:28.011302Z","end":"2026-04-22T06:10:28.809223Z","steps":["trace[724192872] 'read index received' (duration: 742.357122ms)","trace[724192872] 'applied index is now lower than readState.Index' (duration: 55.561802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:28.809519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.049361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T06:10:28.809629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.570867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-22T06:10:28.809682Z","caller":"traceutil/trace.go:171","msg":"trace[213811079] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4962; }","duration":"622.63943ms","start":"2026-04-22T06:10:28.187027Z","end":"2026-04-22T06:10:28.809666Z","steps":["trace[213811079] 'agreement among raft nodes before linearized reading' (duration: 622.537017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.809722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.187015Z","time spent":"622.699852ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-22T06:10:28.809621Z","caller":"traceutil/trace.go:171","msg":"trace[797365884] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4962; }","duration":"689.180975ms","start":"2026-04-22T06:10:28.120422Z","end":"2026-04-22T06:10:28.809603Z","steps":["trace[797365884] 'agreement among raft nodes before linearized reading' (duration: 689.002169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.809891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.120407Z","time spent":"689.436652ms","remote":"127.0.0.1:44166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:10:28.809545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.439472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:10:28.809973Z","caller":"traceutil/trace.go:171","msg":"trace[57425526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4962; }","duration":"271.903496ms","start":"2026-04-22T06:10:28.538057Z","end":"2026-04-22T06:10:28.80996Z","steps":["trace[57425526] 'agreement among raft nodes before linearized reading' (duration: 271.429941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.80952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.223294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-22T06:10:28.81011Z","caller":"traceutil/trace.go:171","msg":"trace[1295997734] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4962; }","duration":"798.852533ms","start":"2026-04-22T06:10:28.011243Z","end":"2026-04-22T06:10:28.810095Z","steps":["trace[1295997734] 'agreement among raft nodes before linearized reading' (duration: 798.141171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:28.810205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:28.011232Z","time spent":"798.954256ms","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-04-22T06:11:01.070486Z","caller":"traceutil/trace.go:171","msg":"trace[140036290] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5373; }","duration":"142.023424ms","start":"2026-04-22T06:11:00.928421Z","end":"2026-04-22T06:11:01.070444Z","steps":["trace[140036290] 'read index received' (duration: 18.457191ms)","trace[140036290] 'applied index is now lower than readState.Index' (duration: 123.564503ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:01.070928Z","caller":"traceutil/trace.go:171","msg":"trace[1249833327] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"154.10801ms","start":"2026-04-22T06:11:00.916782Z","end":"2026-04-22T06:11:01.07089Z","steps":["trace[1249833327] 'process raft request' (duration: 153.184991ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.07086Z","caller":"traceutil/trace.go:171","msg":"trace[1758524698] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"154.013988ms","start":"2026-04-22T06:11:00.91682Z","end":"2026-04-22T06:11:01.070834Z","steps":["trace[1758524698] 'process raft request' (duration: 153.387848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.071352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.691211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T06:11:01.071422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.404134ms","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-22T06:11:01.071501Z","caller":"traceutil/trace.go:171","msg":"trace[1603311748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5178; }","duration":"121.526588ms","start":"2026-04-22T06:11:00.949956Z","end":"2026-04-22T06:11:01.071483Z","steps":["trace[1603311748] 'agreement among raft nodes before linearized reading' (duration: 121.28339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.071614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.681122ms","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-22T06:11:01.071669Z","caller":"traceutil/trace.go:171","msg":"trace[385422646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5178; }","duration":"120.762204ms","start":"2026-04-22T06:11:00.950893Z","end":"2026-04-22T06:11:01.071655Z","steps":["trace[385422646] 'agreement among raft nodes before linearized reading' (duration: 120.583288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.07187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.787185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T06:11:01.071889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.328263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:01.071909Z","caller":"traceutil/trace.go:171","msg":"trace[1324239732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5178; }","duration":"115.845607ms","start":"2026-04-22T06:11:00.956053Z","end":"2026-04-22T06:11:01.071899Z","steps":["trace[1324239732] 'agreement among raft nodes before linearized reading' (duration: 115.701422ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.07193Z","caller":"traceutil/trace.go:171","msg":"trace[83052423] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:5178; }","duration":"121.387495ms","start":"2026-04-22T06:11:00.950529Z","end":"2026-04-22T06:11:01.071917Z","steps":["trace[83052423] 'agreement among raft nodes before linearized reading' (duration: 121.326303ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.071447Z","caller":"traceutil/trace.go:171","msg":"trace[1362047315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5178; }","duration":"110.798965ms","start":"2026-04-22T06:11:00.960613Z","end":"2026-04-22T06:11:01.071412Z","steps":["trace[1362047315] 'agreement among raft nodes before linearized reading' (duration: 110.65081ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.070929Z","caller":"traceutil/trace.go:171","msg":"trace[531732599] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"128.178271ms","start":"2026-04-22T06:11:00.94274Z","end":"2026-04-22T06:11:01.070918Z","steps":["trace[531732599] 'process raft request' (duration: 127.607823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.0723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.869694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T06:11:01.072391Z","caller":"traceutil/trace.go:171","msg":"trace[871542013] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5178; }","duration":"143.979527ms","start":"2026-04-22T06:11:00.928398Z","end":"2026-04-22T06:11:01.072378Z","steps":["trace[871542013] 'agreement among raft nodes before linearized reading' (duration: 142.331253ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.715367Z","caller":"traceutil/trace.go:171","msg":"trace[391780842] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"107.413408ms","start":"2026-04-22T06:11:01.607916Z","end":"2026-04-22T06:11:01.715329Z","steps":["trace[391780842] 'process raft request' (duration: 78.621257ms)","trace[391780842] 'compare' (duration: 28.471871ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:01.715349Z","caller":"traceutil/trace.go:171","msg":"trace[227868836] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5433; }","duration":"106.936042ms","start":"2026-04-22T06:11:01.608364Z","end":"2026-04-22T06:11:01.7153Z","steps":["trace[227868836] 'read index received' (duration: 78.092629ms)","trace[227868836] 'applied index is now lower than readState.Index' (duration: 28.842533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:01.715653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.242123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-04-22T06:11:01.71604Z","caller":"traceutil/trace.go:171","msg":"trace[182753024] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5236; }","duration":"107.389457ms","start":"2026-04-22T06:11:01.608314Z","end":"2026-04-22T06:11:01.715703Z","steps":["trace[182753024] 'agreement among raft nodes before linearized reading' (duration: 107.106878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.716022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.168968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-22T06:11:01.716303Z","caller":"traceutil/trace.go:171","msg":"trace[2021905952] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5236; }","duration":"106.499149ms","start":"2026-04-22T06:11:01.609772Z","end":"2026-04-22T06:11:01.716271Z","steps":["trace[2021905952] 'agreement among raft nodes before linearized reading' (duration: 106.002042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.934981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.955118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:11:01.935214Z","caller":"traceutil/trace.go:171","msg":"trace[1067490449] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"131.025184ms","start":"2026-04-22T06:11:01.804176Z","end":"2026-04-22T06:11:01.935201Z","steps":["trace[1067490449] 'process raft request' (duration: 130.929401ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.935488Z","caller":"traceutil/trace.go:171","msg":"trace[1471454868] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"132.301004ms","start":"2026-04-22T06:11:01.803174Z","end":"2026-04-22T06:11:01.935475Z","steps":["trace[1471454868] 'process raft request' (duration: 131.887741ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:01.935489Z","caller":"traceutil/trace.go:171","msg":"trace[269759219] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"133.484142ms","start":"2026-04-22T06:11:01.801958Z","end":"2026-04-22T06:11:01.935442Z","steps":["trace[269759219] 'read index received' (duration: 14.016368ms)","trace[269759219] 'applied index is now lower than readState.Index' (duration: 119.464774ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:01.935545Z","caller":"traceutil/trace.go:171","msg":"trace[388465178] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"134.775113ms","start":"2026-04-22T06:11:01.800764Z","end":"2026-04-22T06:11:01.935539Z","steps":["trace[388465178] 'process raft request' (duration: 15.198506ms)","trace[388465178] 'compare' (duration: 118.851594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:01.935781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.810433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61950"} {"level":"info","ts":"2026-04-22T06:11:01.935802Z","caller":"traceutil/trace.go:171","msg":"trace[2034394543] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5243; }","duration":"133.843625ms","start":"2026-04-22T06:11:01.801952Z","end":"2026-04-22T06:11:01.935796Z","steps":["trace[2034394543] 'agreement among raft nodes before linearized reading' (duration: 133.626248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.935797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.180799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-04-22T06:11:01.93584Z","caller":"traceutil/trace.go:171","msg":"trace[716025441] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5243; }","duration":"131.2482ms","start":"2026-04-22T06:11:01.804577Z","end":"2026-04-22T06:11:01.935825Z","steps":["trace[716025441] 'agreement among raft nodes before linearized reading' (duration: 131.020813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:01.936107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.729681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-22T06:11:01.936315Z","caller":"traceutil/trace.go:171","msg":"trace[508945017] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5243; }","duration":"130.250198ms","start":"2026-04-22T06:11:01.806042Z","end":"2026-04-22T06:11:01.936292Z","steps":["trace[508945017] 'agreement among raft nodes before linearized reading' (duration: 129.640109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:02.827273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.204351ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-22T06:11:02.836451Z","caller":"traceutil/trace.go:171","msg":"trace[954970547] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"125.81397ms","start":"2026-04-22T06:11:02.710617Z","end":"2026-04-22T06:11:02.836431Z","steps":["trace[954970547] 'process raft request' (duration: 125.696666ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:02.979808Z","caller":"traceutil/trace.go:171","msg":"trace[2126845443] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5468; }","duration":"119.622152ms","start":"2026-04-22T06:11:02.860165Z","end":"2026-04-22T06:11:02.979787Z","steps":["trace[2126845443] 'read index received' (duration: 111.523082ms)","trace[2126845443] 'applied index is now lower than readState.Index' (duration: 8.09817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:02.980013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.805658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-22T06:11:02.980042Z","caller":"traceutil/trace.go:171","msg":"trace[627703637] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"134.691364ms","start":"2026-04-22T06:11:02.845319Z","end":"2026-04-22T06:11:02.980011Z","steps":["trace[627703637] 'process raft request' (duration: 126.289225ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:02.980084Z","caller":"traceutil/trace.go:171","msg":"trace[1944201720] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5270; }","duration":"119.963153ms","start":"2026-04-22T06:11:02.860109Z","end":"2026-04-22T06:11:02.980072Z","steps":["trace[1944201720] 'agreement among raft nodes before linearized reading' (duration: 119.765016ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:03.2802Z","caller":"traceutil/trace.go:171","msg":"trace[1013698401] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"129.348606ms","start":"2026-04-22T06:11:03.150819Z","end":"2026-04-22T06:11:03.280168Z","steps":["trace[1013698401] 'process raft request' (duration: 129.021626ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:03.280263Z","caller":"traceutil/trace.go:171","msg":"trace[1074202135] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"128.560571ms","start":"2026-04-22T06:11:03.151659Z","end":"2026-04-22T06:11:03.28022Z","steps":["trace[1074202135] 'process raft request' (duration: 128.447177ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:03.28084Z","caller":"traceutil/trace.go:171","msg":"trace[1461698367] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"129.951925ms","start":"2026-04-22T06:11:03.150873Z","end":"2026-04-22T06:11:03.280825Z","steps":["trace[1461698367] 'process raft request' (duration: 129.048016ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:03.280973Z","caller":"traceutil/trace.go:171","msg":"trace[702889857] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"130.43078ms","start":"2026-04-22T06:11:03.150494Z","end":"2026-04-22T06:11:03.280925Z","steps":["trace[702889857] 'process raft request' (duration: 113.358993ms)","trace[702889857] 'compare' (duration: 15.742145ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:03.280607Z","caller":"traceutil/trace.go:171","msg":"trace[172197443] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"129.021045ms","start":"2026-04-22T06:11:03.151557Z","end":"2026-04-22T06:11:03.280578Z","steps":["trace[172197443] 'process raft request' (duration: 128.433406ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:03.281232Z","caller":"traceutil/trace.go:171","msg":"trace[1739938456] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"129.615514ms","start":"2026-04-22T06:11:03.151588Z","end":"2026-04-22T06:11:03.281204Z","steps":["trace[1739938456] 'process raft request' (duration: 128.461737ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:05.232115Z","caller":"traceutil/trace.go:171","msg":"trace[298490999] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5489; }","duration":"155.667346ms","start":"2026-04-22T06:11:05.076426Z","end":"2026-04-22T06:11:05.232094Z","steps":["trace[298490999] 'read index received' (duration: 155.635385ms)","trace[298490999] 'applied index is now lower than readState.Index' (duration: 27.701µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:05.232226Z","caller":"traceutil/trace.go:171","msg":"trace[457182739] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"229.557007ms","start":"2026-04-22T06:11:05.002658Z","end":"2026-04-22T06:11:05.232215Z","steps":["trace[457182739] 'process raft request' (duration: 229.289449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.232629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.178522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T06:11:05.232679Z","caller":"traceutil/trace.go:171","msg":"trace[753306596] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5291; }","duration":"156.283345ms","start":"2026-04-22T06:11:05.076383Z","end":"2026-04-22T06:11:05.232666Z","steps":["trace[753306596] 'agreement among raft nodes before linearized reading' (duration: 155.905773ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:05.591559Z","caller":"traceutil/trace.go:171","msg":"trace[734264369] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"442.405508ms","start":"2026-04-22T06:11:05.149109Z","end":"2026-04-22T06:11:05.591514Z","steps":["trace[734264369] 'process raft request' (duration: 434.144303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.591805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.149092Z","time spent":"442.554942ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:11:05.591884Z","caller":"traceutil/trace.go:171","msg":"trace[869079552] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5490; }","duration":"359.676313ms","start":"2026-04-22T06:11:05.232183Z","end":"2026-04-22T06:11:05.591859Z","steps":["trace[869079552] 'read index received' (duration: 351.046186ms)","trace[869079552] 'applied index is now lower than readState.Index' (duration: 8.627877ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:05.592019Z","caller":"traceutil/trace.go:171","msg":"trace[2034958639] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"439.891998ms","start":"2026-04-22T06:11:05.151989Z","end":"2026-04-22T06:11:05.591881Z","steps":["trace[2034958639] 'process raft request' (duration: 439.477764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.629901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:05.592104Z","caller":"traceutil/trace.go:171","msg":"trace[1161329639] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5293; }","duration":"507.716883ms","start":"2026-04-22T06:11:05.084378Z","end":"2026-04-22T06:11:05.592095Z","steps":["trace[1161329639] 'agreement among raft nodes before linearized reading' (duration: 507.61422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.084365Z","time spent":"507.762505ms","remote":"127.0.0.1:36264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:11:05.592188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.530646ms","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-22T06:11:05.592275Z","caller":"traceutil/trace.go:171","msg":"trace[842121124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5293; }","duration":"305.64894ms","start":"2026-04-22T06:11:05.286582Z","end":"2026-04-22T06:11:05.592231Z","steps":["trace[842121124] 'agreement among raft nodes before linearized reading' (duration: 305.444173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.286568Z","time spent":"305.743592ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:11:05.592475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.151976Z","time spent":"440.120294ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:11:05.592494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.916596ms","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-22T06:11:05.592485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.572357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-22T06:11:05.592664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.432102ms","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-22T06:11:05.592681Z","caller":"traceutil/trace.go:171","msg":"trace[667812527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5293; }","duration":"305.804844ms","start":"2026-04-22T06:11:05.286856Z","end":"2026-04-22T06:11:05.592661Z","steps":["trace[667812527] 'agreement among raft nodes before linearized reading' (duration: 305.273448ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:05.592693Z","caller":"traceutil/trace.go:171","msg":"trace[1610501074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5293; }","duration":"305.459553ms","start":"2026-04-22T06:11:05.287227Z","end":"2026-04-22T06:11:05.592687Z","steps":["trace[1610501074] 'agreement among raft nodes before linearized reading' (duration: 305.399061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.287154Z","time spent":"305.569986ms","remote":"127.0.0.1:44016","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-22T06:11:05.592602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.777673ms","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-22T06:11:05.592801Z","caller":"traceutil/trace.go:171","msg":"trace[34422675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5293; }","duration":"306.030052ms","start":"2026-04-22T06:11:05.286764Z","end":"2026-04-22T06:11:05.592794Z","steps":["trace[34422675] 'agreement among raft nodes before linearized reading' (duration: 305.787694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.286734Z","time spent":"306.085984ms","remote":"127.0.0.1:44016","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-22T06:11:05.592595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.69021ms","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-22T06:11:05.592894Z","caller":"traceutil/trace.go:171","msg":"trace[552574421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5293; }","duration":"306.034122ms","start":"2026-04-22T06:11:05.286852Z","end":"2026-04-22T06:11:05.592886Z","steps":["trace[552574421] 'agreement among raft nodes before linearized reading' (duration: 305.623539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.592915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.286843Z","time spent":"306.066062ms","remote":"127.0.0.1:44016","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-22T06:11:05.593077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.286838Z","time spent":"306.161705ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:11:05.59253Z","caller":"traceutil/trace.go:171","msg":"trace[1554635963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5293; }","duration":"304.998609ms","start":"2026-04-22T06:11:05.287524Z","end":"2026-04-22T06:11:05.592523Z","steps":["trace[1554635963] 'agreement among raft nodes before linearized reading' (duration: 304.880065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:05.593247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:05.287482Z","time spent":"305.749743ms","remote":"127.0.0.1:44016","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-22T06:11:07.825195Z","caller":"traceutil/trace.go:171","msg":"trace[534008643] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"226.85739ms","start":"2026-04-22T06:11:07.598312Z","end":"2026-04-22T06:11:07.825169Z","steps":["trace[534008643] 'read index received' (duration: 226.805638ms)","trace[534008643] 'applied index is now lower than readState.Index' (duration: 50.662µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:07.825307Z","caller":"traceutil/trace.go:171","msg":"trace[1324008470] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"227.032266ms","start":"2026-04-22T06:11:07.598263Z","end":"2026-04-22T06:11:07.825295Z","steps":["trace[1324008470] 'process raft request' (duration: 226.673624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:07.825475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.142189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:07.825514Z","caller":"traceutil/trace.go:171","msg":"trace[454836904] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5303; }","duration":"227.223961ms","start":"2026-04-22T06:11:07.59828Z","end":"2026-04-22T06:11:07.825504Z","steps":["trace[454836904] 'agreement among raft nodes before linearized reading' (duration: 227.147559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.813146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.029573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:11:08.813332Z","caller":"traceutil/trace.go:171","msg":"trace[1439722400] linearizableReadLoop","detail":"{readStateIndex:5503; appliedIndex:5502; }","duration":"988.050277ms","start":"2026-04-22T06:11:07.82526Z","end":"2026-04-22T06:11:08.813311Z","steps":["trace[1439722400] 'read index received' (duration: 318.692474ms)","trace[1439722400] 'applied index is now lower than readState.Index' (duration: 669.355683ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:08.813596Z","caller":"traceutil/trace.go:171","msg":"trace[3507747] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"1.215088214s","start":"2026-04-22T06:11:07.598494Z","end":"2026-04-22T06:11:08.813582Z","steps":["trace[3507747] 'process raft request' (duration: 545.535034ms)","trace[3507747] 'compare' (duration: 668.883558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:08.814156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.598473Z","time spent":"1.215642701s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:11:08.813801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204934927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T06:11:08.813854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204953008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T06:11:08.815113Z","caller":"traceutil/trace.go:171","msg":"trace[2077040045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5304; }","duration":"1.206201148s","start":"2026-04-22T06:11:07.608874Z","end":"2026-04-22T06:11:08.815076Z","steps":["trace[2077040045] 'agreement among raft nodes before linearized reading' (duration: 1.204805153s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.815216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.608866Z","time spent":"1.206323362s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:11:08.814413Z","caller":"traceutil/trace.go:171","msg":"trace[1647994069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5304; }","duration":"1.205463863s","start":"2026-04-22T06:11:07.608831Z","end":"2026-04-22T06:11:08.814295Z","steps":["trace[1647994069] 'agreement among raft nodes before linearized reading' (duration: 1.204851974s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.815507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.60882Z","time spent":"1.206664333s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:11:08.813926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215495037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-04-22T06:11:08.815626Z","caller":"traceutil/trace.go:171","msg":"trace[1657209018] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5304; }","duration":"1.217195361s","start":"2026-04-22T06:11:07.598401Z","end":"2026-04-22T06:11:08.815597Z","steps":["trace[1657209018] 'agreement among raft nodes before linearized reading' (duration: 1.215462545s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.815734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.598392Z","time spent":"1.217305465s","remote":"127.0.0.1:43996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-22T06:11:08.813939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205011769s","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-22T06:11:08.8159Z","caller":"traceutil/trace.go:171","msg":"trace[375501567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5304; }","duration":"1.206996844s","start":"2026-04-22T06:11:07.608876Z","end":"2026-04-22T06:11:08.815873Z","steps":["trace[375501567] 'agreement among raft nodes before linearized reading' (duration: 1.205001329s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.816051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.608859Z","time spent":"1.207145478s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:11:08.813958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209736971s","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-22T06:11:08.816344Z","caller":"traceutil/trace.go:171","msg":"trace[238407600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5304; }","duration":"1.212146649s","start":"2026-04-22T06:11:07.604175Z","end":"2026-04-22T06:11:08.816321Z","steps":["trace[238407600] 'agreement among raft nodes before linearized reading' (duration: 1.209511934s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.816458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.604166Z","time spent":"1.212272483s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:11:08.813952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205202376s","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-22T06:11:08.816907Z","caller":"traceutil/trace.go:171","msg":"trace[1679677985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5304; }","duration":"1.208225613s","start":"2026-04-22T06:11:07.608652Z","end":"2026-04-22T06:11:08.816878Z","steps":["trace[1679677985] 'agreement among raft nodes before linearized reading' (duration: 1.204999309s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.817022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.608627Z","time spent":"1.208373338s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:11:08.814064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205398072s","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-22T06:11:08.81855Z","caller":"traceutil/trace.go:171","msg":"trace[1297401396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5304; }","duration":"1.209903377s","start":"2026-04-22T06:11:07.608633Z","end":"2026-04-22T06:11:08.818536Z","steps":["trace[1297401396] 'agreement among raft nodes before linearized reading' (duration: 1.20533699s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:08.818586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:07.608623Z","time spent":"1.209951049s","remote":"127.0.0.1:44016","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-22T06:11:09.043175Z","caller":"traceutil/trace.go:171","msg":"trace[50099361] linearizableReadLoop","detail":"{readStateIndex:5504; appliedIndex:5503; }","duration":"229.747588ms","start":"2026-04-22T06:11:08.813391Z","end":"2026-04-22T06:11:09.043139Z","steps":["trace[50099361] 'read index received' (duration: 179.362277ms)","trace[50099361] 'applied index is now lower than readState.Index' (duration: 50.378671ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:09.043282Z","caller":"traceutil/trace.go:171","msg":"trace[208254228] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"220.570923ms","start":"2026-04-22T06:11:08.822692Z","end":"2026-04-22T06:11:09.043263Z","steps":["trace[208254228] 'process raft request' (duration: 220.537122ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.043433Z","caller":"traceutil/trace.go:171","msg":"trace[1381064190] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"221.321197ms","start":"2026-04-22T06:11:08.82207Z","end":"2026-04-22T06:11:09.043391Z","steps":["trace[1381064190] 'process raft request' (duration: 221.044259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.043531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.836147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:09.04359Z","caller":"traceutil/trace.go:171","msg":"trace[401570622] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5310; }","duration":"677.92189ms","start":"2026-04-22T06:11:08.365637Z","end":"2026-04-22T06:11:09.043559Z","steps":["trace[401570622] 'agreement among raft nodes before linearized reading' (duration: 677.792386ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.043563Z","caller":"traceutil/trace.go:171","msg":"trace[1881932094] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"221.159762ms","start":"2026-04-22T06:11:08.822301Z","end":"2026-04-22T06:11:09.043515Z","steps":["trace[1881932094] 'process raft request' (duration: 220.827852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.043657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.871674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:11:09.043643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.592036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:09.04368Z","caller":"traceutil/trace.go:171","msg":"trace[2131688514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5310; }","duration":"503.917874ms","start":"2026-04-22T06:11:08.539757Z","end":"2026-04-22T06:11:09.043675Z","steps":["trace[2131688514] 'agreement among raft nodes before linearized reading' (duration: 503.883783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.043711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.539743Z","time spent":"503.962186ms","remote":"127.0.0.1:43748","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-22T06:11:09.043734Z","caller":"traceutil/trace.go:171","msg":"trace[558698344] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"221.672799ms","start":"2026-04-22T06:11:08.822049Z","end":"2026-04-22T06:11:09.043722Z","steps":["trace[558698344] 'process raft request' (duration: 220.972377ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.043719Z","caller":"traceutil/trace.go:171","msg":"trace[1553009801] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5310; }","duration":"467.684709ms","start":"2026-04-22T06:11:08.576016Z","end":"2026-04-22T06:11:09.043701Z","steps":["trace[1553009801] 'agreement among raft nodes before linearized reading' (duration: 467.458812ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.043758Z","caller":"traceutil/trace.go:171","msg":"trace[1675833457] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"221.214174ms","start":"2026-04-22T06:11:08.822368Z","end":"2026-04-22T06:11:09.043583Z","steps":["trace[1675833457] 'process raft request' (duration: 220.835652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.043798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.575972Z","time spent":"467.814474ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":34,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:11:09.04395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.03767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:09.043818Z","caller":"traceutil/trace.go:171","msg":"trace[1453902318] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"221.603047ms","start":"2026-04-22T06:11:08.822205Z","end":"2026-04-22T06:11:09.043808Z","steps":["trace[1453902318] 'process raft request' (duration: 220.951466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.043964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.01507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:09.043991Z","caller":"traceutil/trace.go:171","msg":"trace[243410500] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5310; }","duration":"222.143433ms","start":"2026-04-22T06:11:08.82184Z","end":"2026-04-22T06:11:09.043983Z","steps":["trace[243410500] 'agreement among raft nodes before linearized reading' (duration: 222.016199ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.044042Z","caller":"traceutil/trace.go:171","msg":"trace[1156861399] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5310; }","duration":"333.184455ms","start":"2026-04-22T06:11:08.710843Z","end":"2026-04-22T06:11:09.044027Z","steps":["trace[1156861399] 'agreement among raft nodes before linearized reading' (duration: 332.463322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.044143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.710814Z","time spent":"333.314029ms","remote":"127.0.0.1:54782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":24,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:11:09.043622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.365619Z","time spent":"677.995472ms","remote":"127.0.0.1:44018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:11:09.978146Z","caller":"traceutil/trace.go:171","msg":"trace[633536292] linearizableReadLoop","detail":"{readStateIndex:5512; appliedIndex:5511; }","duration":"114.620938ms","start":"2026-04-22T06:11:09.8635Z","end":"2026-04-22T06:11:09.978121Z","steps":["trace[633536292] 'read index received' (duration: 114.334539ms)","trace[633536292] 'applied index is now lower than readState.Index' (duration: 285.449µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:09.978365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.846255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-04-22T06:11:09.978409Z","caller":"traceutil/trace.go:171","msg":"trace[268563646] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5312; }","duration":"114.941428ms","start":"2026-04-22T06:11:09.863455Z","end":"2026-04-22T06:11:09.978396Z","steps":["trace[268563646] 'agreement among raft nodes before linearized reading' (duration: 114.769553ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.978334Z","caller":"traceutil/trace.go:171","msg":"trace[1544251886] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"146.566607ms","start":"2026-04-22T06:11:09.831681Z","end":"2026-04-22T06:11:09.978248Z","steps":["trace[1544251886] 'process raft request' (duration: 146.257007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:10.396716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.7347ms","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-22T06:11:10.396796Z","caller":"traceutil/trace.go:171","msg":"trace[34164374] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5312; }","duration":"258.842474ms","start":"2026-04-22T06:11:10.137933Z","end":"2026-04-22T06:11:10.396776Z","steps":["trace[34164374] 'count revisions from in-memory index tree' (duration: 258.528944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:10.397112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.797151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-22T06:11:10.397295Z","caller":"traceutil/trace.go:171","msg":"trace[2065633680] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5312; }","duration":"345.164443ms","start":"2026-04-22T06:11:10.05209Z","end":"2026-04-22T06:11:10.397254Z","steps":["trace[2065633680] 'range keys from in-memory index tree' (duration: 344.631187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:10.397403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:10.052075Z","time spent":"345.272727ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:11:11.892054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.770649ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:11.892168Z","caller":"traceutil/trace.go:171","msg":"trace[1548696193] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5321; }","duration":"128.891623ms","start":"2026-04-22T06:11:11.763258Z","end":"2026-04-22T06:11:11.89215Z","steps":["trace[1548696193] 'range keys from in-memory index tree' (duration: 128.756018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:11.892668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.113326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:11:11.892721Z","caller":"traceutil/trace.go:171","msg":"trace[500008923] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"354.775539ms","start":"2026-04-22T06:11:11.537936Z","end":"2026-04-22T06:11:11.892711Z","steps":["trace[500008923] 'read index received' (duration: 28.400965ms)","trace[500008923] 'applied index is now lower than readState.Index' (duration: 326.373694ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:11.89283Z","caller":"traceutil/trace.go:171","msg":"trace[283254075] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"601.472007ms","start":"2026-04-22T06:11:11.291342Z","end":"2026-04-22T06:11:11.892814Z","steps":["trace[283254075] 'process raft request' (duration: 275.155384ms)","trace[283254075] 'compare' (duration: 325.076743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:11.892868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.958907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:11:11.892881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.925074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:11.892913Z","caller":"traceutil/trace.go:171","msg":"trace[1426660854] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5322; }","duration":"288.04303ms","start":"2026-04-22T06:11:11.604861Z","end":"2026-04-22T06:11:11.892904Z","steps":["trace[1426660854] 'agreement among raft nodes before linearized reading' (duration: 287.974757ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:11.892934Z","caller":"traceutil/trace.go:171","msg":"trace[1431343332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5322; }","duration":"355.025557ms","start":"2026-04-22T06:11:11.537894Z","end":"2026-04-22T06:11:11.892919Z","steps":["trace[1431343332] 'agreement among raft nodes before linearized reading' (duration: 354.909113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:11.892942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:11.291321Z","time spent":"601.53629ms","remote":"127.0.0.1:44016","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-22T06:11:11.892984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:11.537872Z","time spent":"355.10711ms","remote":"127.0.0.1:43748","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-22T06:11:12.149255Z","caller":"traceutil/trace.go:171","msg":"trace[1677115566] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"165.311608ms","start":"2026-04-22T06:11:11.983922Z","end":"2026-04-22T06:11:12.149234Z","steps":["trace[1677115566] 'process raft request' (duration: 165.183344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:12.47824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.446387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:11:12.478393Z","caller":"traceutil/trace.go:171","msg":"trace[2051409695] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"213.323737ms","start":"2026-04-22T06:11:12.26504Z","end":"2026-04-22T06:11:12.478364Z","steps":["trace[2051409695] 'process raft request' (duration: 68.608463ms)","trace[2051409695] 'compare' (duration: 144.229349ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:17.898738Z","caller":"traceutil/trace.go:171","msg":"trace[2146870152] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"100.104307ms","start":"2026-04-22T06:11:17.798596Z","end":"2026-04-22T06:11:17.8987Z","steps":["trace[2146870152] 'process raft request' (duration: 99.899721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.628436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392855161322559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T06:11:25.774926Z","caller":"traceutil/trace.go:171","msg":"trace[418640334] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"647.020681ms","start":"2026-04-22T06:11:25.12788Z","end":"2026-04-22T06:11:25.774901Z","steps":["trace[418640334] 'read index received' (duration: 646.784843ms)","trace[418640334] 'applied index is now lower than readState.Index' (duration: 234.638µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:25.775331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.841418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"warn","ts":"2026-04-22T06:11:25.775354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.524108ms","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-22T06:11:25.775398Z","caller":"traceutil/trace.go:171","msg":"trace[677734062] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5418; }","duration":"165.969451ms","start":"2026-04-22T06:11:25.609414Z","end":"2026-04-22T06:11:25.775384Z","steps":["trace[677734062] 'agreement among raft nodes before linearized reading' (duration: 165.777305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.775432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.531093ms","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-22T06:11:25.77545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.801242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:11:25.775349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.559733ms","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-22T06:11:25.775543Z","caller":"traceutil/trace.go:171","msg":"trace[1609394593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5418; }","duration":"629.796401ms","start":"2026-04-22T06:11:25.145736Z","end":"2026-04-22T06:11:25.775532Z","steps":["trace[1609394593] 'agreement among raft nodes before linearized reading' (duration: 629.45876ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:25.775556Z","caller":"traceutil/trace.go:171","msg":"trace[1717526170] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5418; }","duration":"147.977798ms","start":"2026-04-22T06:11:25.627553Z","end":"2026-04-22T06:11:25.77553Z","steps":["trace[1717526170] 'agreement among raft nodes before linearized reading' (duration: 147.797372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.775598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.145725Z","time spent":"629.858293ms","remote":"127.0.0.1:44016","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-22T06:11:25.775601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.313285ms","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-22T06:11:25.775662Z","caller":"traceutil/trace.go:171","msg":"trace[1903886963] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5418; }","duration":"633.400318ms","start":"2026-04-22T06:11:25.142247Z","end":"2026-04-22T06:11:25.775647Z","steps":["trace[1903886963] 'agreement among raft nodes before linearized reading' (duration: 633.230742ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:25.775596Z","caller":"traceutil/trace.go:171","msg":"trace[272780509] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"649.816801ms","start":"2026-04-22T06:11:25.125752Z","end":"2026-04-22T06:11:25.775569Z","steps":["trace[272780509] 'process raft request' (duration: 649.003645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.775707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.142231Z","time spent":"633.466899ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:11:25.775499Z","caller":"traceutil/trace.go:171","msg":"trace[211025816] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5418; }","duration":"396.630427ms","start":"2026-04-22T06:11:25.378852Z","end":"2026-04-22T06:11:25.775483Z","steps":["trace[211025816] 'agreement among raft nodes before linearized reading' (duration: 396.529813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.775636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.700403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:11:25.775757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.378838Z","time spent":"396.903445ms","remote":"127.0.0.1:36294","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-22T06:11:25.775836Z","caller":"traceutil/trace.go:171","msg":"trace[1727868952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5418; }","duration":"647.923309ms","start":"2026-04-22T06:11:25.127854Z","end":"2026-04-22T06:11:25.775777Z","steps":["trace[1727868952] 'agreement among raft nodes before linearized reading' (duration: 647.458434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.775909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.127845Z","time spent":"648.047014ms","remote":"127.0.0.1:44016","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-22T06:11:25.775875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.125726Z","time spent":"649.984997ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:11:25.775968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.704695ms","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-22T06:11:25.776025Z","caller":"traceutil/trace.go:171","msg":"trace[829838768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5418; }","duration":"636.800868ms","start":"2026-04-22T06:11:25.13921Z","end":"2026-04-22T06:11:25.776011Z","steps":["trace[829838768] 'agreement among raft nodes before linearized reading' (duration: 635.990022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.776263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.139193Z","time spent":"637.051916ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:11:25.776022Z","caller":"traceutil/trace.go:171","msg":"trace[621417539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5418; }","duration":"631.60643ms","start":"2026-04-22T06:11:25.143788Z","end":"2026-04-22T06:11:25.775395Z","steps":["trace[621417539] 'agreement among raft nodes before linearized reading' (duration: 631.449225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:25.776418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:25.143779Z","time spent":"632.623723ms","remote":"127.0.0.1:44016","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-22T06:11:28.00816Z","caller":"traceutil/trace.go:171","msg":"trace[1544926182] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"149.288645ms","start":"2026-04-22T06:11:27.858853Z","end":"2026-04-22T06:11:28.008141Z","steps":["trace[1544926182] 'process raft request' (duration: 149.220093ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:28.008419Z","caller":"traceutil/trace.go:171","msg":"trace[946228143] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"150.103893ms","start":"2026-04-22T06:11:27.858272Z","end":"2026-04-22T06:11:28.008376Z","steps":["trace[946228143] 'process raft request' (duration: 149.681179ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:28.008675Z","caller":"traceutil/trace.go:171","msg":"trace[1264049655] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"149.594136ms","start":"2026-04-22T06:11:27.859051Z","end":"2026-04-22T06:11:28.008645Z","steps":["trace[1264049655] 'process raft request' (duration: 149.062448ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:28.00877Z","caller":"traceutil/trace.go:171","msg":"trace[334753974] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"148.885482ms","start":"2026-04-22T06:11:27.859865Z","end":"2026-04-22T06:11:28.00875Z","steps":["trace[334753974] 'process raft request' (duration: 148.786469ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:28.008793Z","caller":"traceutil/trace.go:171","msg":"trace[1864441856] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"148.893323ms","start":"2026-04-22T06:11:27.859845Z","end":"2026-04-22T06:11:28.008738Z","steps":["trace[1864441856] 'process raft request' (duration: 148.626464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:32.177284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.172682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:11:32.177349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.339367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:32.177363Z","caller":"traceutil/trace.go:171","msg":"trace[907361141] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5455; }","duration":"102.304296ms","start":"2026-04-22T06:11:32.07504Z","end":"2026-04-22T06:11:32.177344Z","steps":["trace[907361141] 'range keys from in-memory index tree' (duration: 102.073578ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:32.177397Z","caller":"traceutil/trace.go:171","msg":"trace[1357021260] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5455; }","duration":"102.42484ms","start":"2026-04-22T06:11:32.074959Z","end":"2026-04-22T06:11:32.177384Z","steps":["trace[1357021260] 'range keys from in-memory index tree' (duration: 102.267135ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:39.340697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2884} {"level":"info","ts":"2026-04-22T06:11:39.774912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2884,"took":"429.660042ms","hash":840766755,"current-db-size-bytes":52056064,"current-db-size":"52 MB","current-db-size-in-use-bytes":28528640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-22T06:11:39.775013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":840766755,"revision":2884,"compact-revision":-1} {"level":"info","ts":"2026-04-22T06:11:41.737154Z","caller":"traceutil/trace.go:171","msg":"trace[1262178111] linearizableReadLoop","detail":"{readStateIndex:5743; appliedIndex:5742; }","duration":"110.086397ms","start":"2026-04-22T06:11:41.627026Z","end":"2026-04-22T06:11:41.737112Z","steps":["trace[1262178111] 'read index received' (duration: 109.803827ms)","trace[1262178111] 'applied index is now lower than readState.Index' (duration: 281.51µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:41.737264Z","caller":"traceutil/trace.go:171","msg":"trace[698272837] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"170.679382ms","start":"2026-04-22T06:11:41.566519Z","end":"2026-04-22T06:11:41.737198Z","steps":["trace[698272837] 'process raft request' (duration: 170.399252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:41.737409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.315574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:41.737488Z","caller":"traceutil/trace.go:171","msg":"trace[1280677923] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5535; }","duration":"110.502741ms","start":"2026-04-22T06:11:41.626969Z","end":"2026-04-22T06:11:41.737471Z","steps":["trace[1280677923] 'agreement among raft nodes before linearized reading' (duration: 110.336735ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.239932Z","caller":"traceutil/trace.go:171","msg":"trace[970122059] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"102.824614ms","start":"2026-04-22T06:11:50.137081Z","end":"2026-04-22T06:11:50.239905Z","steps":["trace[970122059] 'process raft request' (duration: 102.581866ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.485111Z","caller":"traceutil/trace.go:171","msg":"trace[703067147] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"166.671083ms","start":"2026-04-22T06:11:50.3184Z","end":"2026-04-22T06:11:50.485072Z","steps":["trace[703067147] 'process raft request' (duration: 121.061533ms)","trace[703067147] 'compare' (duration: 45.375732ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:50.485764Z","caller":"traceutil/trace.go:171","msg":"trace[203250937] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"130.595158ms","start":"2026-04-22T06:11:50.355149Z","end":"2026-04-22T06:11:50.485744Z","steps":["trace[203250937] 'process raft request' (duration: 129.860563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:50.486181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.818158ms","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-22T06:11:50.486228Z","caller":"traceutil/trace.go:171","msg":"trace[132498919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5607; }","duration":"138.933532ms","start":"2026-04-22T06:11:50.347282Z","end":"2026-04-22T06:11:50.486215Z","steps":["trace[132498919] 'agreement among raft nodes before linearized reading' (duration: 138.765367ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.486624Z","caller":"traceutil/trace.go:171","msg":"trace[912105133] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5814; }","duration":"139.250302ms","start":"2026-04-22T06:11:50.347346Z","end":"2026-04-22T06:11:50.486596Z","steps":["trace[912105133] 'read index received' (duration: 54.282115ms)","trace[912105133] 'applied index is now lower than readState.Index' (duration: 83.637272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:50.48726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.215021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T06:11:50.487317Z","caller":"traceutil/trace.go:171","msg":"trace[1484317052] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5607; }","duration":"115.290874ms","start":"2026-04-22T06:11:50.372009Z","end":"2026-04-22T06:11:50.4873Z","steps":["trace[1484317052] 'agreement among raft nodes before linearized reading' (duration: 115.150869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:50.488815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.03448ms","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-22T06:11:50.488847Z","caller":"traceutil/trace.go:171","msg":"trace[1935676762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5607; }","duration":"121.862611ms","start":"2026-04-22T06:11:50.366975Z","end":"2026-04-22T06:11:50.488838Z","steps":["trace[1935676762] 'agreement among raft nodes before linearized reading' (duration: 119.967758ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.739258Z","caller":"traceutil/trace.go:171","msg":"trace[1886635934] linearizableReadLoop","detail":"{readStateIndex:5820; appliedIndex:5818; }","duration":"197.735897ms","start":"2026-04-22T06:11:50.541499Z","end":"2026-04-22T06:11:50.739235Z","steps":["trace[1886635934] 'read index received' (duration: 44.809865ms)","trace[1886635934] 'applied index is now lower than readState.Index' (duration: 152.925062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:50.739848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.328436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:11:50.739899Z","caller":"traceutil/trace.go:171","msg":"trace[271173534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5610; }","duration":"198.423689ms","start":"2026-04-22T06:11:50.541463Z","end":"2026-04-22T06:11:50.739887Z","steps":["trace[271173534] 'agreement among raft nodes before linearized reading' (duration: 198.321606ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.740095Z","caller":"traceutil/trace.go:171","msg":"trace[1793134096] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"245.032921ms","start":"2026-04-22T06:11:50.495049Z","end":"2026-04-22T06:11:50.740082Z","steps":["trace[1793134096] 'process raft request' (duration: 244.134692ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:50.739996Z","caller":"traceutil/trace.go:171","msg":"trace[1570307695] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"247.164582ms","start":"2026-04-22T06:11:50.492801Z","end":"2026-04-22T06:11:50.739966Z","steps":["trace[1570307695] 'process raft request' (duration: 156.053905ms)","trace[1570307695] 'compare' (duration: 90.101634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:12:07.880208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.107263ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:12:07.880302Z","caller":"traceutil/trace.go:171","msg":"trace[925644614] linearizableReadLoop","detail":"{readStateIndex:5957; appliedIndex:5956; }","duration":"127.748034ms","start":"2026-04-22T06:12:07.752537Z","end":"2026-04-22T06:12:07.880285Z","steps":["trace[925644614] 'read index received' (duration: 3.489855ms)","trace[925644614] 'applied index is now lower than readState.Index' (duration: 124.256669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:12:07.88046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.908939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-domain-manage-w96sz\" ","response":"range_response_count:1 size:7904"} {"level":"info","ts":"2026-04-22T06:12:07.880497Z","caller":"traceutil/trace.go:171","msg":"trace[1052015160] range","detail":"{range_begin:/registry/pods/openstack/keystone-domain-manage-w96sz; range_end:; response_count:1; response_revision:5743; }","duration":"128.007882ms","start":"2026-04-22T06:12:07.752479Z","end":"2026-04-22T06:12:07.880487Z","steps":["trace[1052015160] 'agreement among raft nodes before linearized reading' (duration: 127.851337ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:08.052863Z","caller":"traceutil/trace.go:171","msg":"trace[1312802260] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"162.816426ms","start":"2026-04-22T06:12:07.890019Z","end":"2026-04-22T06:12:08.052835Z","steps":["trace[1312802260] 'process raft request' (duration: 140.325321ms)","trace[1312802260] 'compare' (duration: 22.363551ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:00.665264Z","caller":"traceutil/trace.go:171","msg":"trace[1338211660] linearizableReadLoop","detail":"{readStateIndex:6727; appliedIndex:6726; }","duration":"128.728103ms","start":"2026-04-22T06:14:00.536513Z","end":"2026-04-22T06:14:00.665241Z","steps":["trace[1338211660] 'read index received' (duration: 126.424094ms)","trace[1338211660] 'applied index is now lower than readState.Index' (duration: 2.302409ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:00.66537Z","caller":"traceutil/trace.go:171","msg":"trace[281444455] transaction","detail":"{read_only:false; response_revision:6486; number_of_response:1; }","duration":"136.84378ms","start":"2026-04-22T06:14:00.528489Z","end":"2026-04-22T06:14:00.665333Z","steps":["trace[281444455] 'process raft request' (duration: 134.521611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:00.665441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.910559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:00.665688Z","caller":"traceutil/trace.go:171","msg":"trace[1877551647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6486; }","duration":"129.190928ms","start":"2026-04-22T06:14:00.536476Z","end":"2026-04-22T06:14:00.665667Z","steps":["trace[1877551647] 'agreement among raft nodes before linearized reading' (duration: 128.893518ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.108341Z","caller":"traceutil/trace.go:171","msg":"trace[1743022987] transaction","detail":"{read_only:false; response_revision:6639; number_of_response:1; }","duration":"155.165169ms","start":"2026-04-22T06:14:23.953152Z","end":"2026-04-22T06:14:24.108317Z","steps":["trace[1743022987] 'process raft request' (duration: 154.633573ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.463841Z","caller":"traceutil/trace.go:171","msg":"trace[691037228] transaction","detail":"{read_only:false; response_revision:6643; number_of_response:1; }","duration":"162.912093ms","start":"2026-04-22T06:14:25.300735Z","end":"2026-04-22T06:14:25.463647Z","steps":["trace[691037228] 'process raft request' (duration: 105.808937ms)","trace[691037228] 'compare' (duration: 56.58531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:26.369445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.395061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:14:26.369694Z","caller":"traceutil/trace.go:171","msg":"trace[828101759] transaction","detail":"{read_only:false; response_revision:6649; number_of_response:1; }","duration":"158.217674ms","start":"2026-04-22T06:14:26.211451Z","end":"2026-04-22T06:14:26.369668Z","steps":["trace[828101759] 'process raft request' (duration: 31.399597ms)","trace[828101759] 'compare' (duration: 126.198719ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:26.601051Z","caller":"traceutil/trace.go:171","msg":"trace[285179012] transaction","detail":"{read_only:false; response_revision:6650; number_of_response:1; }","duration":"225.958045ms","start":"2026-04-22T06:14:26.375057Z","end":"2026-04-22T06:14:26.601015Z","steps":["trace[285179012] 'process raft request' (duration: 225.728673ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:37.23874Z","caller":"traceutil/trace.go:171","msg":"trace[226933217] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"183.643624ms","start":"2026-04-22T06:14:37.055079Z","end":"2026-04-22T06:14:37.238723Z","steps":["trace[226933217] 'process raft request' (duration: 183.527052ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:37.352905Z","caller":"traceutil/trace.go:171","msg":"trace[964388578] transaction","detail":"{read_only:false; response_revision:6758; number_of_response:1; }","duration":"149.554855ms","start":"2026-04-22T06:14:37.203313Z","end":"2026-04-22T06:14:37.352868Z","steps":["trace[964388578] 'process raft request' (duration: 149.507144ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:37.352987Z","caller":"traceutil/trace.go:171","msg":"trace[780621906] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"200.945905ms","start":"2026-04-22T06:14:37.151981Z","end":"2026-04-22T06:14:37.352927Z","steps":["trace[780621906] 'process raft request' (duration: 161.760734ms)","trace[780621906] 'compare' (duration: 38.911397ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:43.519522Z","caller":"traceutil/trace.go:171","msg":"trace[167973368] linearizableReadLoop","detail":"{readStateIndex:7043; appliedIndex:7041; }","duration":"129.155995ms","start":"2026-04-22T06:14:43.390348Z","end":"2026-04-22T06:14:43.519504Z","steps":["trace[167973368] 'read index received' (duration: 31.733085ms)","trace[167973368] 'applied index is now lower than readState.Index' (duration: 97.4219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:43.519671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.306317ms","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-22T06:14:43.519693Z","caller":"traceutil/trace.go:171","msg":"trace[1852033504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6789; }","duration":"129.377168ms","start":"2026-04-22T06:14:43.390308Z","end":"2026-04-22T06:14:43.519685Z","steps":["trace[1852033504] 'agreement among raft nodes before linearized reading' (duration: 129.268367ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:43.519705Z","caller":"traceutil/trace.go:171","msg":"trace[442248181] transaction","detail":"{read_only:false; response_revision:6789; number_of_response:1; }","duration":"141.675654ms","start":"2026-04-22T06:14:43.378017Z","end":"2026-04-22T06:14:43.519693Z","steps":["trace[442248181] 'process raft request' (duration: 141.37894ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:44.020077Z","caller":"traceutil/trace.go:171","msg":"trace[1476582669] transaction","detail":"{read_only:false; response_revision:6795; number_of_response:1; }","duration":"181.415161ms","start":"2026-04-22T06:14:43.838626Z","end":"2026-04-22T06:14:44.020041Z","steps":["trace[1476582669] 'process raft request' (duration: 157.757009ms)","trace[1476582669] 'compare' (duration: 22.984264ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:15:03.983175Z","caller":"traceutil/trace.go:171","msg":"trace[1495451099] transaction","detail":"{read_only:false; response_revision:6900; number_of_response:1; }","duration":"108.385278ms","start":"2026-04-22T06:15:03.874761Z","end":"2026-04-22T06:15:03.983146Z","steps":["trace[1495451099] 'process raft request' (duration: 16.299412ms)","trace[1495451099] 'compare' (duration: 91.966334ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:15:10.48892Z","caller":"traceutil/trace.go:171","msg":"trace[1827659111] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"207.824799ms","start":"2026-04-22T06:15:10.280881Z","end":"2026-04-22T06:15:10.488706Z","steps":["trace[1827659111] 'process raft request' (duration: 207.694357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:10.795774Z","caller":"traceutil/trace.go:171","msg":"trace[601997834] transaction","detail":"{read_only:false; response_revision:6932; number_of_response:1; }","duration":"409.541889ms","start":"2026-04-22T06:15:10.386208Z","end":"2026-04-22T06:15:10.79575Z","steps":["trace[601997834] 'process raft request' (duration: 409.395377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:10.79595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:10.386184Z","time spent":"409.666521ms","remote":"127.0.0.1:44016","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-22T06:15:10.796498Z","caller":"traceutil/trace.go:171","msg":"trace[1093924962] linearizableReadLoop","detail":"{readStateIndex:7191; appliedIndex:7191; }","duration":"282.14024ms","start":"2026-04-22T06:15:10.514346Z","end":"2026-04-22T06:15:10.796486Z","steps":["trace[1093924962] 'read index received' (duration: 282.13525ms)","trace[1093924962] 'applied index is now lower than readState.Index' (duration: 3.8µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:15:10.796563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.211521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:15:10.79659Z","caller":"traceutil/trace.go:171","msg":"trace[1629523200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:6932; }","duration":"282.276382ms","start":"2026-04-22T06:15:10.514306Z","end":"2026-04-22T06:15:10.796582Z","steps":["trace[1629523200] 'agreement among raft nodes before linearized reading' (duration: 282.226211ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:32.217809Z","caller":"traceutil/trace.go:171","msg":"trace[1218733794] transaction","detail":"{read_only:false; response_revision:7061; number_of_response:1; }","duration":"101.513055ms","start":"2026-04-22T06:15:32.116257Z","end":"2026-04-22T06:15:32.21777Z","steps":["trace[1218733794] 'process raft request' (duration: 101.108069ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:32.217832Z","caller":"traceutil/trace.go:171","msg":"trace[339920942] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"101.536386ms","start":"2026-04-22T06:15:32.116277Z","end":"2026-04-22T06:15:32.217813Z","steps":["trace[339920942] 'process raft request' (duration: 101.311512ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:32.240801Z","caller":"traceutil/trace.go:171","msg":"trace[1871907793] transaction","detail":"{read_only:false; response_revision:7063; number_of_response:1; }","duration":"124.466336ms","start":"2026-04-22T06:15:32.116323Z","end":"2026-04-22T06:15:32.24079Z","steps":["trace[1871907793] 'process raft request' (duration: 124.374034ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:32.240766Z","caller":"traceutil/trace.go:171","msg":"trace[757422802] linearizableReadLoop","detail":"{readStateIndex:7327; appliedIndex:7324; }","duration":"117.30315ms","start":"2026-04-22T06:15:32.12345Z","end":"2026-04-22T06:15:32.240754Z","steps":["trace[757422802] 'read index received' (duration: 93.903213ms)","trace[757422802] 'applied index is now lower than readState.Index' (duration: 23.399087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:15:32.241124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.481434ms","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-22T06:15:32.241147Z","caller":"traceutil/trace.go:171","msg":"trace[1192456081] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7063; }","duration":"104.571195ms","start":"2026-04-22T06:15:32.136569Z","end":"2026-04-22T06:15:32.24114Z","steps":["trace[1192456081] 'agreement among raft nodes before linearized reading' (duration: 104.449863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:32.241124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.649005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" ","response":"range_response_count:1 size:2888"} {"level":"info","ts":"2026-04-22T06:15:32.241203Z","caller":"traceutil/trace.go:171","msg":"trace[2145022851] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy; range_end:; response_count:1; response_revision:7063; }","duration":"117.776997ms","start":"2026-04-22T06:15:32.123406Z","end":"2026-04-22T06:15:32.241183Z","steps":["trace[2145022851] 'agreement among raft nodes before linearized reading' (duration: 117.485702ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:42.161877Z","caller":"traceutil/trace.go:171","msg":"trace[1411997703] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"191.668483ms","start":"2026-04-22T06:15:41.970183Z","end":"2026-04-22T06:15:42.161852Z","steps":["trace[1411997703] 'process raft request' (duration: 190.91604ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:52.40093Z","caller":"traceutil/trace.go:171","msg":"trace[812835764] transaction","detail":"{read_only:false; response_revision:7161; number_of_response:1; }","duration":"193.900217ms","start":"2026-04-22T06:15:52.207007Z","end":"2026-04-22T06:15:52.400907Z","steps":["trace[812835764] 'process raft request' (duration: 193.453429ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:16:39.358726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5520} {"level":"info","ts":"2026-04-22T06:16:39.448603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5520,"took":"87.364139ms","hash":1531694587,"current-db-size-bytes":52056064,"current-db-size":"52 MB","current-db-size-in-use-bytes":18382848,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T06:16:39.448697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1531694587,"revision":5520,"compact-revision":2884}