{"level":"warn","ts":"2026-05-28T21:39:16.093657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:39:16.093789Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-05-28T21:39:16.093884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:39:16.093894Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-28T21:39:16.093914Z","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-05-28T21:39:16.094729Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-28T21:39:16.094824Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-05-28T21:39:16.098668Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.562549ms"} {"level":"info","ts":"2026-05-28T21:39:16.105898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-28T21:39:16.105992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:39:16.106015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-28T21:39:16.106026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:39:16.106037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-28T21:39:16.106072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-28T21:39:16.112871Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:39:16.114198Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:39:16.115161Z","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-05-28T21:39:16.1172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:39:16.117351Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:39:16.117578Z","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-05-28T21:39:16.117727Z","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-05-28T21:39:16.118029Z","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-05-28T21:39:16.122274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-28T21:39:16.122438Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-28T21:39:16.124984Z","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-05-28T21:39:16.125104Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-28T21:39:16.125152Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-28T21:39:16.125277Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:39:16.125342Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:39:16.306465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:39:16.30658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:39:16.306613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-28T21:39:16.306668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:39:16.306713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-28T21:39:16.306768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-28T21:39:16.306798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-28T21:39:16.307841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:39:16.307889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:39:16.307913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:39:16.308138Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:39:16.308268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:39:16.308291Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:39:16.30917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:39:16.309276Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:39:16.309341Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:39:16.309772Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:39:16.311323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"warn","ts":"2026-05-28T21:39:23.602263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.9281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-28T21:39:23.602361Z","caller":"traceutil/trace.go:171","msg":"trace[1660577286] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"315.083101ms","start":"2026-05-28T21:39:23.287263Z","end":"2026-05-28T21:39:23.602346Z","steps":["trace[1660577286] 'range keys from in-memory index tree' (duration: 314.801689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:39:23.602425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:39:23.287241Z","time spent":"315.175902ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-28T21:39:23.780628Z","caller":"traceutil/trace.go:171","msg":"trace[419608201] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"174.973866ms","start":"2026-05-28T21:39:23.605624Z","end":"2026-05-28T21:39:23.780597Z","steps":["trace[419608201] 'process raft request' (duration: 174.696994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:39:24.077435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.691218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:39:24.07751Z","caller":"traceutil/trace.go:171","msg":"trace[1514473615] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"290.48776ms","start":"2026-05-28T21:39:23.787012Z","end":"2026-05-28T21:39:24.0775Z","steps":["trace[1514473615] 'read index received' (duration: 34.22898ms)","trace[1514473615] 'applied index is now lower than readState.Index' (duration: 256.25793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:39:24.077587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.57461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-28T21:39:24.077604Z","caller":"traceutil/trace.go:171","msg":"trace[1860749065] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"290.619632ms","start":"2026-05-28T21:39:23.786978Z","end":"2026-05-28T21:39:24.077598Z","steps":["trace[1860749065] 'agreement among raft nodes before linearized reading' (duration: 290.579711ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:24.077565Z","caller":"traceutil/trace.go:171","msg":"trace[290239768] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"292.43688ms","start":"2026-05-28T21:39:23.785097Z","end":"2026-05-28T21:39:24.077533Z","steps":["trace[290239768] 'process raft request' (duration: 36.21575ms)","trace[290239768] 'compare' (duration: 255.603707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:39:24.077792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.047644ms","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-05-28T21:39:24.07785Z","caller":"traceutil/trace.go:171","msg":"trace[1220183067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:276; }","duration":"175.138315ms","start":"2026-05-28T21:39:23.902694Z","end":"2026-05-28T21:39:24.077832Z","steps":["trace[1220183067] 'agreement among raft nodes before linearized reading' (duration: 175.059224ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:53.066141Z","caller":"traceutil/trace.go:171","msg":"trace[1122697849] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"143.103513ms","start":"2026-05-28T21:39:52.923012Z","end":"2026-05-28T21:39:53.066115Z","steps":["trace[1122697849] 'process raft request' (duration: 83.18142ms)","trace[1122697849] 'compare' (duration: 59.598271ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:39:53.074375Z","caller":"traceutil/trace.go:171","msg":"trace[1870424067] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"109.626292ms","start":"2026-05-28T21:39:52.964726Z","end":"2026-05-28T21:39:53.074353Z","steps":["trace[1870424067] 'process raft request' (duration: 109.094072ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:58.50761Z","caller":"traceutil/trace.go:171","msg":"trace[208108855] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"183.176292ms","start":"2026-05-28T21:39:58.324386Z","end":"2026-05-28T21:39:58.507562Z","steps":["trace[208108855] 'process raft request' (duration: 182.890152ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:58.507757Z","caller":"traceutil/trace.go:171","msg":"trace[1924798974] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:606; }","duration":"178.676562ms","start":"2026-05-28T21:39:58.328999Z","end":"2026-05-28T21:39:58.507676Z","steps":["trace[1924798974] 'read index received' (duration: 178.665411ms)","trace[1924798974] 'applied index is now lower than readState.Index' (duration: 8.8µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:39:58.507967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.904871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:39:58.508044Z","caller":"traceutil/trace.go:171","msg":"trace[995989349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"179.038726ms","start":"2026-05-28T21:39:58.328987Z","end":"2026-05-28T21:39:58.508026Z","steps":["trace[995989349] 'agreement among raft nodes before linearized reading' (duration: 178.87663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:39:58.550817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.718428ms","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-05-28T21:39:58.550844Z","caller":"traceutil/trace.go:171","msg":"trace[625457154] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"214.52749ms","start":"2026-05-28T21:39:58.336284Z","end":"2026-05-28T21:39:58.550812Z","steps":["trace[625457154] 'process raft request' (duration: 213.818015ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:58.550928Z","caller":"traceutil/trace.go:171","msg":"trace[1567960682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"220.852683ms","start":"2026-05-28T21:39:58.330056Z","end":"2026-05-28T21:39:58.550908Z","steps":["trace[1567960682] 'agreement among raft nodes before linearized reading' (duration: 220.669226ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:39:58.933388Z","caller":"traceutil/trace.go:171","msg":"trace[153461540] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"322.841606ms","start":"2026-05-28T21:39:58.610508Z","end":"2026-05-28T21:39:58.93335Z","steps":["trace[153461540] 'process raft request' (duration: 322.625798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:39:58.933564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:39:58.610487Z","time spent":"323.002412ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:39:59.174717Z","caller":"traceutil/trace.go:171","msg":"trace[660925135] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"227.636576ms","start":"2026-05-28T21:39:58.947048Z","end":"2026-05-28T21:39:59.174684Z","steps":["trace[660925135] 'process raft request' (duration: 209.360977ms)","trace[660925135] 'compare' (duration: 18.151664ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:40:54.887806Z","caller":"traceutil/trace.go:171","msg":"trace[285913502] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"123.871386ms","start":"2026-05-28T21:40:54.763916Z","end":"2026-05-28T21:40:54.887787Z","steps":["trace[285913502] 'read index received' (duration: 123.713833ms)","trace[285913502] 'applied index is now lower than readState.Index' (duration: 156.643µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:40:54.887916Z","caller":"traceutil/trace.go:171","msg":"trace[1329639392] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"276.473505ms","start":"2026-05-28T21:40:54.611432Z","end":"2026-05-28T21:40:54.887906Z","steps":["trace[1329639392] 'process raft request' (duration: 276.238081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:40:54.888231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.306274ms","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-05-28T21:40:54.888303Z","caller":"traceutil/trace.go:171","msg":"trace[2095748642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"124.422297ms","start":"2026-05-28T21:40:54.763863Z","end":"2026-05-28T21:40:54.888285Z","steps":["trace[2095748642] 'agreement among raft nodes before linearized reading' (duration: 124.286124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:40:59.304265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.258576ms","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-05-28T21:40:59.304415Z","caller":"traceutil/trace.go:171","msg":"trace[1618391221] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"204.928308ms","start":"2026-05-28T21:40:59.099461Z","end":"2026-05-28T21:40:59.30439Z","steps":["trace[1618391221] 'process raft request' (duration: 128.207661ms)","trace[1618391221] 'compare' (duration: 76.545624ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:40:59.304437Z","caller":"traceutil/trace.go:171","msg":"trace[1463282397] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1232; }","duration":"161.46182ms","start":"2026-05-28T21:40:59.142957Z","end":"2026-05-28T21:40:59.304418Z","steps":["trace[1463282397] 'range keys from in-memory index tree' (duration: 161.243396ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:40:59.314866Z","caller":"traceutil/trace.go:171","msg":"trace[1926367269] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"215.153687ms","start":"2026-05-28T21:40:59.099698Z","end":"2026-05-28T21:40:59.314851Z","steps":["trace[1926367269] 'process raft request' (duration: 215.015155ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:40:59.314842Z","caller":"traceutil/trace.go:171","msg":"trace[931202853] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"211.997056ms","start":"2026-05-28T21:40:59.10283Z","end":"2026-05-28T21:40:59.314827Z","steps":["trace[931202853] 'process raft request' (duration: 211.953885ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:40:59.835193Z","caller":"traceutil/trace.go:171","msg":"trace[1333964623] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"262.95191ms","start":"2026-05-28T21:40:59.572209Z","end":"2026-05-28T21:40:59.83516Z","steps":["trace[1333964623] 'process raft request' (duration: 262.816657ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:40:59.923588Z","caller":"traceutil/trace.go:171","msg":"trace[1219789471] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1255; }","duration":"345.95929ms","start":"2026-05-28T21:40:59.577603Z","end":"2026-05-28T21:40:59.923563Z","steps":["trace[1219789471] 'process raft request' (duration: 345.786167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:40:59.923733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:40:59.577588Z","time spent":"346.070242ms","remote":"127.0.0.1:58154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":70,"response count":0,"response size":2546,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:41:00.242274Z","caller":"traceutil/trace.go:171","msg":"trace[1228724366] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"279.571527ms","start":"2026-05-28T21:40:59.962677Z","end":"2026-05-28T21:41:00.242248Z","steps":["trace[1228724366] 'read index received' (duration: 278.567227ms)","trace[1228724366] 'applied index is now lower than readState.Index' (duration: 1.00298ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:41:00.242429Z","caller":"traceutil/trace.go:171","msg":"trace[2053593046] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"289.43313ms","start":"2026-05-28T21:40:59.952985Z","end":"2026-05-28T21:41:00.242418Z","steps":["trace[2053593046] 'process raft request' (duration: 288.319268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:00.242658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.972915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:41:00.242701Z","caller":"traceutil/trace.go:171","msg":"trace[1199490350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"280.063616ms","start":"2026-05-28T21:40:59.962626Z","end":"2026-05-28T21:41:00.242689Z","steps":["trace[1199490350] 'agreement among raft nodes before linearized reading' (duration: 279.992175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:44.768008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.719682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11f20937-74a1-45b9-9291-eb4a75ad5594\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-28T21:41:44.768122Z","caller":"traceutil/trace.go:171","msg":"trace[998223980] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11f20937-74a1-45b9-9291-eb4a75ad5594; range_end:; response_count:1; response_revision:1865; }","duration":"823.942114ms","start":"2026-05-28T21:41:43.944154Z","end":"2026-05-28T21:41:44.768096Z","steps":["trace[998223980] 'range keys from in-memory index tree' (duration: 823.522239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:44.768179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:43.944138Z","time spent":"824.025615ms","remote":"127.0.0.1:55286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11f20937-74a1-45b9-9291-eb4a75ad5594\" "} {"level":"warn","ts":"2026-05-28T21:41:44.768568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.350246ms","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-05-28T21:41:44.768652Z","caller":"traceutil/trace.go:171","msg":"trace[631026985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1865; }","duration":"729.61454ms","start":"2026-05-28T21:41:44.039018Z","end":"2026-05-28T21:41:44.768632Z","steps":["trace[631026985] 'range keys from in-memory index tree' (duration: 729.162923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:44.7687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:44.039004Z","time spent":"729.684831ms","remote":"127.0.0.1:48650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-28T21:41:44.768845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.560237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:41:44.768908Z","caller":"traceutil/trace.go:171","msg":"trace[1273490495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"803.666569ms","start":"2026-05-28T21:41:43.965225Z","end":"2026-05-28T21:41:44.768892Z","steps":["trace[1273490495] 'range keys from in-memory index tree' (duration: 803.479166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:44.768947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:43.965204Z","time spent":"803.732849ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:41:44.769039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.040699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:41:44.769842Z","caller":"traceutil/trace.go:171","msg":"trace[1315872492] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"149.912859ms","start":"2026-05-28T21:41:44.619713Z","end":"2026-05-28T21:41:44.769626Z","steps":["trace[1315872492] 'process raft request' (duration: 149.731077ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:41:44.770086Z","caller":"traceutil/trace.go:171","msg":"trace[1246220377] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"232.744952ms","start":"2026-05-28T21:41:44.537272Z","end":"2026-05-28T21:41:44.770017Z","steps":["trace[1246220377] 'process raft request' (duration: 232.007ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:41:44.770163Z","caller":"traceutil/trace.go:171","msg":"trace[332514663] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"660.558697ms","start":"2026-05-28T21:41:44.109536Z","end":"2026-05-28T21:41:44.770095Z","steps":["trace[332514663] 'process raft request' (duration: 509.35213ms)","trace[332514663] 'compare' (duration: 149.099586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:41:44.770263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:44.109511Z","time spent":"660.700669ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:41:45.080935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.672873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:41:45.081068Z","caller":"traceutil/trace.go:171","msg":"trace[2044061648] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"295.622529ms","start":"2026-05-28T21:41:44.785427Z","end":"2026-05-28T21:41:45.081049Z","steps":["trace[2044061648] 'process raft request' (duration: 153.752304ms)","trace[2044061648] 'compare' (duration: 141.550081ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:41:45.606112Z","caller":"traceutil/trace.go:171","msg":"trace[1046711920] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"818.157622ms","start":"2026-05-28T21:41:44.787936Z","end":"2026-05-28T21:41:45.606093Z","steps":["trace[1046711920] 'process raft request' (duration: 811.694758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:45.606407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:44.787925Z","time spent":"818.251653ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:41:45.619105Z","caller":"traceutil/trace.go:171","msg":"trace[248603102] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"366.850662ms","start":"2026-05-28T21:41:45.252231Z","end":"2026-05-28T21:41:45.619082Z","steps":["trace[248603102] 'process raft request' (duration: 366.692669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:45.619246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:45.252207Z","time spent":"366.967624ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:41:52.11634Z","caller":"traceutil/trace.go:171","msg":"trace[1157286191] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2012; }","duration":"275.135491ms","start":"2026-05-28T21:41:51.841189Z","end":"2026-05-28T21:41:52.116324Z","steps":["trace[1157286191] 'read index received' (duration: 265.197003ms)","trace[1157286191] 'applied index is now lower than readState.Index' (duration: 9.937708ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:41:52.116445Z","caller":"traceutil/trace.go:171","msg":"trace[199394943] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"276.716813ms","start":"2026-05-28T21:41:51.839695Z","end":"2026-05-28T21:41:52.116412Z","steps":["trace[199394943] 'process raft request' (duration: 266.747205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:52.116499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.285113ms","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-05-28T21:41:52.116527Z","caller":"traceutil/trace.go:171","msg":"trace[299232317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1936; }","duration":"275.346274ms","start":"2026-05-28T21:41:51.841173Z","end":"2026-05-28T21:41:52.116519Z","steps":["trace[299232317] 'agreement among raft nodes before linearized reading' (duration: 275.234382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:52.116648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.024735ms","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-05-28T21:41:52.116622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.358044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-28T21:41:52.116687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.324425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:41:52.116702Z","caller":"traceutil/trace.go:171","msg":"trace[188171293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1936; }","duration":"151.364465ms","start":"2026-05-28T21:41:51.965332Z","end":"2026-05-28T21:41:52.116697Z","steps":["trace[188171293] 'agreement among raft nodes before linearized reading' (duration: 151.342135ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:41:52.116663Z","caller":"traceutil/trace.go:171","msg":"trace[1248583112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1936; }","duration":"269.056795ms","start":"2026-05-28T21:41:51.847602Z","end":"2026-05-28T21:41:52.116658Z","steps":["trace[1248583112] 'agreement among raft nodes before linearized reading' (duration: 269.010585ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:41:52.116695Z","caller":"traceutil/trace.go:171","msg":"trace[1770476629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1936; }","duration":"275.460335ms","start":"2026-05-28T21:41:51.841221Z","end":"2026-05-28T21:41:52.116681Z","steps":["trace[1770476629] 'agreement among raft nodes before linearized reading' (duration: 275.284932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:52.117046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.982629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:41:52.117572Z","caller":"traceutil/trace.go:171","msg":"trace[86773072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1936; }","duration":"270.528617ms","start":"2026-05-28T21:41:51.84703Z","end":"2026-05-28T21:41:52.117559Z","steps":["trace[86773072] 'agreement among raft nodes before linearized reading' (duration: 269.712705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:57.107858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.464607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:41:57.107935Z","caller":"traceutil/trace.go:171","msg":"trace[1351245534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1968; }","duration":"145.580928ms","start":"2026-05-28T21:41:56.962335Z","end":"2026-05-28T21:41:57.107916Z","steps":["trace[1351245534] 'range keys from in-memory index tree' (duration: 145.399925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.161761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532699729369556,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:41:58.49592Z","caller":"traceutil/trace.go:171","msg":"trace[1347918250] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"838.056376ms","start":"2026-05-28T21:41:57.657841Z","end":"2026-05-28T21:41:58.495897Z","steps":["trace[1347918250] 'process raft request' (duration: 837.902954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.496233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.657824Z","time spent":"838.161107ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:41:58.561111Z","caller":"traceutil/trace.go:171","msg":"trace[1120005113] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2046; }","duration":"900.048678ms","start":"2026-05-28T21:41:57.661037Z","end":"2026-05-28T21:41:58.561086Z","steps":["trace[1120005113] 'read index received' (duration: 834.767631ms)","trace[1120005113] 'applied index is now lower than readState.Index' (duration: 65.279867ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:41:58.561332Z","caller":"traceutil/trace.go:171","msg":"trace[1496958803] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"800.172396ms","start":"2026-05-28T21:41:57.761126Z","end":"2026-05-28T21:41:58.561298Z","steps":["trace[1496958803] 'process raft request' (duration: 799.555458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.56142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.359912ms","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-05-28T21:41:58.561475Z","caller":"traceutil/trace.go:171","msg":"trace[1686979572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1970; }","duration":"900.425282ms","start":"2026-05-28T21:41:57.661033Z","end":"2026-05-28T21:41:58.561458Z","steps":["trace[1686979572] 'agreement among raft nodes before linearized reading' (duration: 900.24915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.661003Z","time spent":"900.505414ms","remote":"127.0.0.1:55370","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":"warn","ts":"2026-05-28T21:41:58.561534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.761097Z","time spent":"800.344998ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:41:58.561576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.514995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:41:58.561588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.572514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:41:58.561623Z","caller":"traceutil/trace.go:171","msg":"trace[265245331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"595.603496ms","start":"2026-05-28T21:41:57.966006Z","end":"2026-05-28T21:41:58.56161Z","steps":["trace[265245331] 'agreement among raft nodes before linearized reading' (duration: 595.524175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.069219ms","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-05-28T21:41:58.561645Z","caller":"traceutil/trace.go:171","msg":"trace[457513199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1970; }","duration":"413.670146ms","start":"2026-05-28T21:41:58.147961Z","end":"2026-05-28T21:41:58.561632Z","steps":["trace[457513199] 'agreement among raft nodes before linearized reading' (duration: 413.472733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.965985Z","time spent":"595.666677ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:41:58.561684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:58.147944Z","time spent":"413.734256ms","remote":"127.0.0.1:55370","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-05-28T21:41:58.561676Z","caller":"traceutil/trace.go:171","msg":"trace[1470894618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1970; }","duration":"422.17561ms","start":"2026-05-28T21:41:58.139487Z","end":"2026-05-28T21:41:58.561663Z","steps":["trace[1470894618] 'agreement among raft nodes before linearized reading' (duration: 421.956727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:58.139467Z","time spent":"422.243122ms","remote":"127.0.0.1:55370","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-05-28T21:41:58.561592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.423501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:41:58.56178Z","caller":"traceutil/trace.go:171","msg":"trace[629333268] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1970; }","duration":"828.676954ms","start":"2026-05-28T21:41:57.733083Z","end":"2026-05-28T21:41:58.56176Z","steps":["trace[629333268] 'agreement among raft nodes before linearized reading' (duration: 828.33853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.51716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:41:58.561828Z","caller":"traceutil/trace.go:171","msg":"trace[1985551332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1970; }","duration":"415.56444ms","start":"2026-05-28T21:41:58.146256Z","end":"2026-05-28T21:41:58.561821Z","steps":["trace[1985551332] 'agreement among raft nodes before linearized reading' (duration: 415.505859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.561829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.733064Z","time spent":"828.748915ms","remote":"127.0.0.1:47194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:41:58.561844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:58.14624Z","time spent":"415.60041ms","remote":"127.0.0.1:55370","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-05-28T21:41:58.561808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.043955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-28T21:41:58.561923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.188517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T21:41:58.561967Z","caller":"traceutil/trace.go:171","msg":"trace[963443135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1970; }","duration":"410.270218ms","start":"2026-05-28T21:41:58.151685Z","end":"2026-05-28T21:41:58.561955Z","steps":["trace[963443135] 'agreement among raft nodes before linearized reading' (duration: 409.767811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.56201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:58.151665Z","time spent":"410.33731ms","remote":"127.0.0.1:55370","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":"info","ts":"2026-05-28T21:41:58.561913Z","caller":"traceutil/trace.go:171","msg":"trace[723819269] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"704.159846ms","start":"2026-05-28T21:41:57.857728Z","end":"2026-05-28T21:41:58.561888Z","steps":["trace[723819269] 'agreement among raft nodes before linearized reading' (duration: 703.901183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.56225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.584426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-28T21:41:58.562267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:57.857712Z","time spent":"704.4659ms","remote":"127.0.0.1:48650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-28T21:41:58.562337Z","caller":"traceutil/trace.go:171","msg":"trace[2117236554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1970; }","duration":"422.719758ms","start":"2026-05-28T21:41:58.139598Z","end":"2026-05-28T21:41:58.562318Z","steps":["trace[2117236554] 'agreement among raft nodes before linearized reading' (duration: 421.822346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:41:58.562399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:41:58.139578Z","time spent":"422.811179ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T21:42:20.280651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.984534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:42:20.281067Z","caller":"traceutil/trace.go:171","msg":"trace[1658662567] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"316.348899ms","start":"2026-05-28T21:42:19.964699Z","end":"2026-05-28T21:42:20.281048Z","steps":["trace[1658662567] 'read index received' (duration: 117.91444ms)","trace[1658662567] 'applied index is now lower than readState.Index' (duration: 198.431859ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:42:20.281121Z","caller":"traceutil/trace.go:171","msg":"trace[1091790954] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"346.85496ms","start":"2026-05-28T21:42:19.934215Z","end":"2026-05-28T21:42:20.28107Z","steps":["trace[1091790954] 'process raft request' (duration: 148.36015ms)","trace[1091790954] 'compare' (duration: 197.765341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:20.28124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.536011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:42:20.281277Z","caller":"traceutil/trace.go:171","msg":"trace[410159992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2104; }","duration":"316.606972ms","start":"2026-05-28T21:42:19.964661Z","end":"2026-05-28T21:42:20.281268Z","steps":["trace[410159992] 'agreement among raft nodes before linearized reading' (duration: 316.44736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.28131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:19.964645Z","time spent":"316.657712ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:42:20.281337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:19.934197Z","time spent":"347.053992ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:42:20.980556Z","caller":"traceutil/trace.go:171","msg":"trace[1956133381] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"339.292352ms","start":"2026-05-28T21:42:20.641244Z","end":"2026-05-28T21:42:20.980536Z","steps":["trace[1956133381] 'process raft request' (duration: 270.065311ms)","trace[1956133381] 'compare' (duration: 68.886307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:20.980664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:20.641222Z","time spent":"339.386223ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:42:20.980728Z","caller":"traceutil/trace.go:171","msg":"trace[2095717268] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"306.608595ms","start":"2026-05-28T21:42:20.674103Z","end":"2026-05-28T21:42:20.980711Z","steps":["trace[2095717268] 'read index received' (duration: 237.05673ms)","trace[2095717268] 'applied index is now lower than readState.Index' (duration: 69.549885ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:20.980906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.132234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:42:20.980936Z","caller":"traceutil/trace.go:171","msg":"trace[827183818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"304.201756ms","start":"2026-05-28T21:42:20.676727Z","end":"2026-05-28T21:42:20.980928Z","steps":["trace[827183818] 'agreement among raft nodes before linearized reading' (duration: 304.111765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.980954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:20.676705Z","time spent":"304.245016ms","remote":"127.0.0.1:55370","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-05-28T21:42:20.980962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.815537ms","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-05-28T21:42:20.981004Z","caller":"traceutil/trace.go:171","msg":"trace[1285469712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"306.944509ms","start":"2026-05-28T21:42:20.674048Z","end":"2026-05-28T21:42:20.980993Z","steps":["trace[1285469712] 'agreement among raft nodes before linearized reading' (duration: 306.777037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.981037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:20.674026Z","time spent":"307.00247ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:42:20.981278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.802217ms","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-05-28T21:42:20.981306Z","caller":"traceutil/trace.go:171","msg":"trace[1317011247] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"273.868407ms","start":"2026-05-28T21:42:20.707431Z","end":"2026-05-28T21:42:20.981299Z","steps":["trace[1317011247] 'agreement among raft nodes before linearized reading' (duration: 273.805606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.981493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.345822ms","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-05-28T21:42:20.981517Z","caller":"traceutil/trace.go:171","msg":"trace[2016718125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2107; }","duration":"298.370672ms","start":"2026-05-28T21:42:20.683141Z","end":"2026-05-28T21:42:20.981511Z","steps":["trace[2016718125] 'agreement among raft nodes before linearized reading' (duration: 298.298911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.981604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.111453ms","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-05-28T21:42:20.981624Z","caller":"traceutil/trace.go:171","msg":"trace[1623887320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2107; }","duration":"304.152334ms","start":"2026-05-28T21:42:20.677467Z","end":"2026-05-28T21:42:20.981619Z","steps":["trace[1623887320] 'agreement among raft nodes before linearized reading' (duration: 304.103394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:20.98164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:20.677456Z","time spent":"304.179664ms","remote":"127.0.0.1:55370","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-05-28T21:42:22.020472Z","caller":"traceutil/trace.go:171","msg":"trace[413871647] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"129.977601ms","start":"2026-05-28T21:42:21.890462Z","end":"2026-05-28T21:42:22.02044Z","steps":["trace[413871647] 'process raft request' (duration: 129.740668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:22.534451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.903848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b3d8b0f2c8a67c\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-28T21:42:22.534521Z","caller":"traceutil/trace.go:171","msg":"trace[870292440] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b3d8b0f2c8a67c; range_end:; response_count:1; response_revision:2114; }","duration":"293.01612ms","start":"2026-05-28T21:42:22.241493Z","end":"2026-05-28T21:42:22.534509Z","steps":["trace[870292440] 'range keys from in-memory index tree' (duration: 292.745346ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:22.555943Z","caller":"traceutil/trace.go:171","msg":"trace[1780289522] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"253.316948ms","start":"2026-05-28T21:42:22.302604Z","end":"2026-05-28T21:42:22.555921Z","steps":["trace[1780289522] 'process raft request' (duration: 253.199437ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:22.55671Z","caller":"traceutil/trace.go:171","msg":"trace[1359330016] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2198; }","duration":"217.775829ms","start":"2026-05-28T21:42:22.33891Z","end":"2026-05-28T21:42:22.556686Z","steps":["trace[1359330016] 'read index received' (duration: 217.767899ms)","trace[1359330016] 'applied index is now lower than readState.Index' (duration: 6.24µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:22.556988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.027663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-28T21:42:22.557024Z","caller":"traceutil/trace.go:171","msg":"trace[857776091] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2115; }","duration":"218.156765ms","start":"2026-05-28T21:42:22.338857Z","end":"2026-05-28T21:42:22.557014Z","steps":["trace[857776091] 'agreement among raft nodes before linearized reading' (duration: 217.914831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:23.134261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532699729369904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:42:23.753928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.957273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:42:23.75411Z","caller":"traceutil/trace.go:171","msg":"trace[1405900441] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"1.130726471s","start":"2026-05-28T21:42:22.623357Z","end":"2026-05-28T21:42:23.754083Z","steps":["trace[1405900441] 'process raft request' (duration: 933.750286ms)","trace[1405900441] 'compare' (duration: 194.244612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:23.754291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:22.623336Z","time spent":"1.130914183s","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:42:24.78565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.029619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:42:24.786008Z","caller":"traceutil/trace.go:171","msg":"trace[1583010150] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2199; }","duration":"2.152327908s","start":"2026-05-28T21:42:22.633657Z","end":"2026-05-28T21:42:24.785985Z","steps":["trace[1583010150] 'read index received' (duration: 923.461198ms)","trace[1583010150] 'applied index is now lower than readState.Index' (duration: 1.22886507s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:42:24.78646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784790126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:42:24.786493Z","caller":"traceutil/trace.go:171","msg":"trace[946440356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2119; }","duration":"1.784875628s","start":"2026-05-28T21:42:23.001608Z","end":"2026-05-28T21:42:24.786484Z","steps":["trace[946440356] 'agreement among raft nodes before linearized reading' (duration: 1.784759086s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.78654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.001588Z","time spent":"1.784943848s","remote":"127.0.0.1:55370","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-05-28T21:42:24.786704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.372382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:42:24.786703Z","caller":"traceutil/trace.go:171","msg":"trace[263620166] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"1.800129965s","start":"2026-05-28T21:42:22.986512Z","end":"2026-05-28T21:42:24.786642Z","steps":["trace[263620166] 'process raft request' (duration: 1.231971813s)","trace[263620166] 'compare' (duration: 566.849416ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:42:24.786731Z","caller":"traceutil/trace.go:171","msg":"trace[1194266780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2119; }","duration":"821.435832ms","start":"2026-05-28T21:42:23.965287Z","end":"2026-05-28T21:42:24.786723Z","steps":["trace[1194266780] 'agreement among raft nodes before linearized reading' (duration: 821.396112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.786777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.965264Z","time spent":"821.503204ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:42:24.786455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152793293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-28T21:42:24.786844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:22.986472Z","time spent":"1.800276938s","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:42:24.786905Z","caller":"traceutil/trace.go:171","msg":"trace[557179890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"2.15327498s","start":"2026-05-28T21:42:22.633613Z","end":"2026-05-28T21:42:24.786888Z","steps":["trace[557179890] 'agreement among raft nodes before linearized reading' (duration: 2.152729373s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.787004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:22.633593Z","time spent":"2.153398112s","remote":"127.0.0.1:48650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-28T21:42:24.787316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740557491s","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-05-28T21:42:24.787412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741467943s","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-05-28T21:42:24.787389Z","caller":"traceutil/trace.go:171","msg":"trace[1158554874] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"1.800196157s","start":"2026-05-28T21:42:22.987078Z","end":"2026-05-28T21:42:24.787274Z","steps":["trace[1158554874] 'process raft request' (duration: 1.798748289s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:24.787418Z","caller":"traceutil/trace.go:171","msg":"trace[30120299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2119; }","duration":"1.740697384s","start":"2026-05-28T21:42:23.046704Z","end":"2026-05-28T21:42:24.787402Z","steps":["trace[30120299] 'agreement among raft nodes before linearized reading' (duration: 1.74041747s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:24.787461Z","caller":"traceutil/trace.go:171","msg":"trace[1942126446] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2119; }","duration":"1.741516973s","start":"2026-05-28T21:42:23.045912Z","end":"2026-05-28T21:42:24.787429Z","steps":["trace[1942126446] 'agreement among raft nodes before linearized reading' (duration: 1.741447062s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.787462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.046693Z","time spent":"1.740755884s","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:42:24.787489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.045902Z","time spent":"1.741581114s","remote":"127.0.0.1:55370","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":"warn","ts":"2026-05-28T21:42:24.787526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:22.987036Z","time spent":"1.800413559s","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:42:24.787633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759699707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-28T21:42:24.787638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740107605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:42:24.787724Z","caller":"traceutil/trace.go:171","msg":"trace[1428763424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2119; }","duration":"1.740209656s","start":"2026-05-28T21:42:23.047503Z","end":"2026-05-28T21:42:24.787712Z","steps":["trace[1428763424] 'agreement among raft nodes before linearized reading' (duration: 1.739973383s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.787798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.047492Z","time spent":"1.740294477s","remote":"127.0.0.1:55370","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-05-28T21:42:24.787924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197463844s","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":"warn","ts":"2026-05-28T21:42:24.787927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740909026s","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-05-28T21:42:24.787966Z","caller":"traceutil/trace.go:171","msg":"trace[1346067602] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2119; }","duration":"1.197602346s","start":"2026-05-28T21:42:23.590341Z","end":"2026-05-28T21:42:24.787943Z","steps":["trace[1346067602] 'agreement among raft nodes before linearized reading' (duration: 1.197357683s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:24.787977Z","caller":"traceutil/trace.go:171","msg":"trace[1411887518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2119; }","duration":"1.740975676s","start":"2026-05-28T21:42:23.046988Z","end":"2026-05-28T21:42:24.787963Z","steps":["trace[1411887518] 'agreement among raft nodes before linearized reading' (duration: 1.740782634s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.787994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.590316Z","time spent":"1.197671397s","remote":"127.0.0.1:55202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":254,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:42:24.78803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.04698Z","time spent":"1.741039117s","remote":"127.0.0.1:55370","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-05-28T21:42:24.787742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74056363s","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-05-28T21:42:24.788183Z","caller":"traceutil/trace.go:171","msg":"trace[743299526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2119; }","duration":"1.741051457s","start":"2026-05-28T21:42:23.047113Z","end":"2026-05-28T21:42:24.788164Z","steps":["trace[743299526] 'agreement among raft nodes before linearized reading' (duration: 1.740534021s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:42:24.787677Z","caller":"traceutil/trace.go:171","msg":"trace[1898607703] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"1.759766408s","start":"2026-05-28T21:42:23.027885Z","end":"2026-05-28T21:42:24.787652Z","steps":["trace[1898607703] 'agreement among raft nodes before linearized reading' (duration: 1.759684307s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:42:24.788295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.047085Z","time spent":"1.741190848s","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:42:24.788557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:42:23.027866Z","time spent":"1.760669138s","remote":"127.0.0.1:48650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-28T21:43:27.460464Z","caller":"traceutil/trace.go:171","msg":"trace[196766942] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"440.888167ms","start":"2026-05-28T21:43:27.019551Z","end":"2026-05-28T21:43:27.460439Z","steps":["trace[196766942] 'process raft request' (duration: 440.739625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.460618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.019529Z","time spent":"441.012778ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:43:27.464556Z","caller":"traceutil/trace.go:171","msg":"trace[143858310] linearizableReadLoop","detail":"{readStateIndex:2657; appliedIndex:2655; }","duration":"368.764989ms","start":"2026-05-28T21:43:27.095769Z","end":"2026-05-28T21:43:27.464534Z","steps":["trace[143858310] 'read index received' (duration: 364.573484ms)","trace[143858310] 'applied index is now lower than readState.Index' (duration: 4.190725ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:43:27.464679Z","caller":"traceutil/trace.go:171","msg":"trace[1642588695] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"439.092728ms","start":"2026-05-28T21:43:27.025576Z","end":"2026-05-28T21:43:27.464669Z","steps":["trace[1642588695] 'process raft request' (duration: 438.838006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.464787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.049546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T21:43:27.464835Z","caller":"traceutil/trace.go:171","msg":"trace[1445131641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2559; }","duration":"360.120977ms","start":"2026-05-28T21:43:27.104703Z","end":"2026-05-28T21:43:27.464824Z","steps":["trace[1445131641] 'agreement among raft nodes before linearized reading' (duration: 360.007596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.464859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.104681Z","time spent":"360.172957ms","remote":"127.0.0.1:55370","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-05-28T21:43:27.464977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.929399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T21:43:27.465004Z","caller":"traceutil/trace.go:171","msg":"trace[122540918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2559; }","duration":"322.967701ms","start":"2026-05-28T21:43:27.14203Z","end":"2026-05-28T21:43:27.464998Z","steps":["trace[122540918] 'agreement among raft nodes before linearized reading' (duration: 322.90872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.465004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.186423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-28T21:43:27.465023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.142021Z","time spent":"322.997701ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T21:43:27.465044Z","caller":"traceutil/trace.go:171","msg":"trace[92953997] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2559; }","duration":"292.284423ms","start":"2026-05-28T21:43:27.172747Z","end":"2026-05-28T21:43:27.465032Z","steps":["trace[92953997] 'agreement among raft nodes before linearized reading' (duration: 292.074201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.46513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.44498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:43:27.465176Z","caller":"traceutil/trace.go:171","msg":"trace[619766706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2559; }","duration":"329.4883ms","start":"2026-05-28T21:43:27.135662Z","end":"2026-05-28T21:43:27.465151Z","steps":["trace[619766706] 'agreement among raft nodes before linearized reading' (duration: 329.42768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.465196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.135652Z","time spent":"329.539991ms","remote":"127.0.0.1:55370","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-05-28T21:43:27.465277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.617832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:43:27.4653Z","caller":"traceutil/trace.go:171","msg":"trace[1737302049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2559; }","duration":"329.653362ms","start":"2026-05-28T21:43:27.135641Z","end":"2026-05-28T21:43:27.465295Z","steps":["trace[1737302049] 'agreement among raft nodes before linearized reading' (duration: 329.602681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.464786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.025548Z","time spent":"439.164149ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:43:27.465317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.135634Z","time spent":"329.679022ms","remote":"127.0.0.1:55370","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-05-28T21:43:27.4654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.299206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-28T21:43:27.465422Z","caller":"traceutil/trace.go:171","msg":"trace[1833187203] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2559; }","duration":"158.347696ms","start":"2026-05-28T21:43:27.30707Z","end":"2026-05-28T21:43:27.465417Z","steps":["trace[1833187203] 'agreement among raft nodes before linearized reading' (duration: 158.289565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.465434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.668388ms","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-05-28T21:43:27.46546Z","caller":"traceutil/trace.go:171","msg":"trace[643929522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2559; }","duration":"369.725438ms","start":"2026-05-28T21:43:27.095728Z","end":"2026-05-28T21:43:27.465453Z","steps":["trace[643929522] 'agreement among raft nodes before linearized reading' (duration: 369.652838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:43:27.465476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:43:27.095709Z","time spent":"369.76377ms","remote":"127.0.0.1:55370","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-05-28T21:44:00.221018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.035161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:00.221107Z","caller":"traceutil/trace.go:171","msg":"trace[395812558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"255.181277ms","start":"2026-05-28T21:43:59.96591Z","end":"2026-05-28T21:44:00.221092Z","steps":["trace[395812558] 'range keys from in-memory index tree' (duration: 254.834504ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:00.221577Z","caller":"traceutil/trace.go:171","msg":"trace[710825335] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"137.844059ms","start":"2026-05-28T21:44:00.083722Z","end":"2026-05-28T21:44:00.221566Z","steps":["trace[710825335] 'process raft request' (duration: 83.423579ms)","trace[710825335] 'compare' (duration: 54.358337ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:44:00.365925Z","caller":"traceutil/trace.go:171","msg":"trace[432343760] linearizableReadLoop","detail":"{readStateIndex:2850; appliedIndex:2849; }","duration":"143.932497ms","start":"2026-05-28T21:44:00.221966Z","end":"2026-05-28T21:44:00.365898Z","steps":["trace[432343760] 'read index received' (duration: 136.343203ms)","trace[432343760] 'applied index is now lower than readState.Index' (duration: 7.588274ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:44:00.36629Z","caller":"traceutil/trace.go:171","msg":"trace[2094406862] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"224.733738ms","start":"2026-05-28T21:44:00.141367Z","end":"2026-05-28T21:44:00.366101Z","steps":["trace[2094406862] 'process raft request' (duration: 217.004928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:00.366953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.929566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:00.3671Z","caller":"traceutil/trace.go:171","msg":"trace[2041745672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2744; }","duration":"145.119272ms","start":"2026-05-28T21:44:00.221926Z","end":"2026-05-28T21:44:00.367045Z","steps":["trace[2041745672] 'agreement among raft nodes before linearized reading' (duration: 144.344794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:00.844786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.475795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:00.844891Z","caller":"traceutil/trace.go:171","msg":"trace[478174393] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2744; }","duration":"201.5993ms","start":"2026-05-28T21:44:00.643266Z","end":"2026-05-28T21:44:00.844865Z","steps":["trace[478174393] 'count revisions from in-memory index tree' (duration: 201.394972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:00.845089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.462866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b3d8c5857f3ecf\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-28T21:44:00.845144Z","caller":"traceutil/trace.go:171","msg":"trace[2131731137] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b3d8c5857f3ecf; range_end:; response_count:1; response_revision:2744; }","duration":"240.553031ms","start":"2026-05-28T21:44:00.604574Z","end":"2026-05-28T21:44:00.845127Z","steps":["trace[2131731137] 'range keys from in-memory index tree' (duration: 240.240908ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:01.085349Z","caller":"traceutil/trace.go:171","msg":"trace[897210506] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"237.294423ms","start":"2026-05-28T21:44:00.848031Z","end":"2026-05-28T21:44:01.085325Z","steps":["trace[897210506] 'process raft request' (duration: 237.093845ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:01.234004Z","caller":"traceutil/trace.go:171","msg":"trace[1454980835] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"118.051304ms","start":"2026-05-28T21:44:01.115924Z","end":"2026-05-28T21:44:01.233975Z","steps":["trace[1454980835] 'process raft request' (duration: 113.6335ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:26.110291Z","caller":"traceutil/trace.go:171","msg":"trace[2090188615] transaction","detail":"{read_only:false; response_revision:3190; number_of_response:1; }","duration":"124.300476ms","start":"2026-05-28T21:45:25.985963Z","end":"2026-05-28T21:45:26.110264Z","steps":["trace[2090188615] 'process raft request' (duration: 87.502466ms)","trace[2090188615] 'compare' (duration: 36.569964ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:26.229284Z","caller":"traceutil/trace.go:171","msg":"trace[1616024228] transaction","detail":"{read_only:false; response_revision:3192; number_of_response:1; }","duration":"114.132666ms","start":"2026-05-28T21:45:26.115125Z","end":"2026-05-28T21:45:26.229257Z","steps":["trace[1616024228] 'process raft request' (duration: 111.242492ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:26.229699Z","caller":"traceutil/trace.go:171","msg":"trace[1202383706] transaction","detail":"{read_only:false; response_revision:3194; number_of_response:1; }","duration":"113.801087ms","start":"2026-05-28T21:45:26.115869Z","end":"2026-05-28T21:45:26.229671Z","steps":["trace[1202383706] 'process raft request' (duration: 113.613132ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:26.22983Z","caller":"traceutil/trace.go:171","msg":"trace[2072229709] transaction","detail":"{read_only:false; response_revision:3195; number_of_response:1; }","duration":"113.819147ms","start":"2026-05-28T21:45:26.115971Z","end":"2026-05-28T21:45:26.22979Z","steps":["trace[2072229709] 'process raft request' (duration: 113.650253ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:26.229882Z","caller":"traceutil/trace.go:171","msg":"trace[905930061] transaction","detail":"{read_only:false; response_revision:3196; number_of_response:1; }","duration":"112.896363ms","start":"2026-05-28T21:45:26.116976Z","end":"2026-05-28T21:45:26.229872Z","steps":["trace[905930061] 'process raft request' (duration: 112.686018ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:26.229575Z","caller":"traceutil/trace.go:171","msg":"trace[68085534] transaction","detail":"{read_only:false; response_revision:3193; number_of_response:1; }","duration":"113.765816ms","start":"2026-05-28T21:45:26.115793Z","end":"2026-05-28T21:45:26.229559Z","steps":["trace[68085534] 'process raft request' (duration: 113.409347ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:42.762485Z","caller":"traceutil/trace.go:171","msg":"trace[1597459870] transaction","detail":"{read_only:false; response_revision:3278; number_of_response:1; }","duration":"168.336493ms","start":"2026-05-28T21:45:42.594026Z","end":"2026-05-28T21:45:42.762362Z","steps":["trace[1597459870] 'process raft request' (duration: 166.763476ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:49.414663Z","caller":"traceutil/trace.go:171","msg":"trace[1826202396] transaction","detail":"{read_only:false; response_revision:3327; number_of_response:1; }","duration":"142.005987ms","start":"2026-05-28T21:45:49.272631Z","end":"2026-05-28T21:45:49.414637Z","steps":["trace[1826202396] 'process raft request' (duration: 131.479209ms)","trace[1826202396] 'compare' (duration: 10.264002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:52.409436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.295172ms","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-05-28T21:45:52.409525Z","caller":"traceutil/trace.go:171","msg":"trace[199643486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3405; }","duration":"106.424565ms","start":"2026-05-28T21:45:52.30308Z","end":"2026-05-28T21:45:52.409504Z","steps":["trace[199643486] 'range keys from in-memory index tree' (duration: 106.183489ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:52.621987Z","caller":"traceutil/trace.go:171","msg":"trace[98134159] transaction","detail":"{read_only:false; response_revision:3407; number_of_response:1; }","duration":"208.559369ms","start":"2026-05-28T21:45:52.413396Z","end":"2026-05-28T21:45:52.621955Z","steps":["trace[98134159] 'process raft request' (duration: 208.16697ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:53.103655Z","caller":"traceutil/trace.go:171","msg":"trace[30083704] transaction","detail":"{read_only:false; response_revision:3412; number_of_response:1; }","duration":"299.24966ms","start":"2026-05-28T21:45:52.804378Z","end":"2026-05-28T21:45:53.103627Z","steps":["trace[30083704] 'process raft request' (duration: 294.358907ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:04.867435Z","caller":"traceutil/trace.go:171","msg":"trace[118774247] linearizableReadLoop","detail":"{readStateIndex:3616; appliedIndex:3615; }","duration":"129.171536ms","start":"2026-05-28T21:46:04.738242Z","end":"2026-05-28T21:46:04.867414Z","steps":["trace[118774247] 'read index received' (duration: 103.576424ms)","trace[118774247] 'applied index is now lower than readState.Index' (duration: 25.594102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:04.867839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.377552ms","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-05-28T21:46:04.867892Z","caller":"traceutil/trace.go:171","msg":"trace[1301596562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3482; }","duration":"100.470804ms","start":"2026-05-28T21:46:04.76741Z","end":"2026-05-28T21:46:04.867881Z","steps":["trace[1301596562] 'agreement among raft nodes before linearized reading' (duration: 100.305051ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:04.867524Z","caller":"traceutil/trace.go:171","msg":"trace[668003786] transaction","detail":"{read_only:false; response_revision:3482; number_of_response:1; }","duration":"129.375341ms","start":"2026-05-28T21:46:04.738122Z","end":"2026-05-28T21:46:04.867497Z","steps":["trace[668003786] 'process raft request' (duration: 103.763039ms)","trace[668003786] 'compare' (duration: 25.438388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:04.868146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.891452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T21:46:04.868203Z","caller":"traceutil/trace.go:171","msg":"trace[1979790999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3482; }","duration":"129.988804ms","start":"2026-05-28T21:46:04.738199Z","end":"2026-05-28T21:46:04.868188Z","steps":["trace[1979790999] 'agreement among raft nodes before linearized reading' (duration: 129.34754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:05.078307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.682179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:05.078371Z","caller":"traceutil/trace.go:171","msg":"trace[790285766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3485; }","duration":"115.762022ms","start":"2026-05-28T21:46:04.962599Z","end":"2026-05-28T21:46:05.078361Z","steps":["trace[790285766] 'agreement among raft nodes before linearized reading' (duration: 115.645008ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:05.078203Z","caller":"traceutil/trace.go:171","msg":"trace[1383129066] linearizableReadLoop","detail":"{readStateIndex:3620; appliedIndex:3619; }","duration":"115.508415ms","start":"2026-05-28T21:46:04.962604Z","end":"2026-05-28T21:46:05.078113Z","steps":["trace[1383129066] 'read index received' (duration: 35.581575ms)","trace[1383129066] 'applied index is now lower than readState.Index' (duration: 79.92462ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:10.723124Z","caller":"traceutil/trace.go:171","msg":"trace[1171902027] transaction","detail":"{read_only:false; response_revision:3514; number_of_response:1; }","duration":"119.469189ms","start":"2026-05-28T21:46:10.603626Z","end":"2026-05-28T21:46:10.723095Z","steps":["trace[1171902027] 'process raft request' (duration: 119.304784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:39.261961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.779362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:46:39.262605Z","caller":"traceutil/trace.go:171","msg":"trace[547710014] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:3668; }","duration":"127.485866ms","start":"2026-05-28T21:46:39.135095Z","end":"2026-05-28T21:46:39.262581Z","steps":["trace[547710014] 'count revisions from in-memory index tree' (duration: 126.67875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:39.262012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.890718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:46:39.262845Z","caller":"traceutil/trace.go:171","msg":"trace[1339334913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3668; }","duration":"152.764975ms","start":"2026-05-28T21:46:39.110057Z","end":"2026-05-28T21:46:39.262822Z","steps":["trace[1339334913] 'range keys from in-memory index tree' (duration: 151.711584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:39.262438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.71978ms","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-05-28T21:46:39.263121Z","caller":"traceutil/trace.go:171","msg":"trace[1408903332] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3668; }","duration":"119.419624ms","start":"2026-05-28T21:46:39.143689Z","end":"2026-05-28T21:46:39.263109Z","steps":["trace[1408903332] 'range keys from in-memory index tree' (duration: 118.70935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:39.711215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.74857ms","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-05-28T21:46:39.711329Z","caller":"traceutil/trace.go:171","msg":"trace[1728122505] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:3673; }","duration":"117.919043ms","start":"2026-05-28T21:46:39.593384Z","end":"2026-05-28T21:46:39.711303Z","steps":["trace[1728122505] 'count revisions from in-memory index tree' (duration: 117.591567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:39.711518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.196988ms","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-05-28T21:46:39.711602Z","caller":"traceutil/trace.go:171","msg":"trace[1309495000] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3673; }","duration":"105.33884ms","start":"2026-05-28T21:46:39.606241Z","end":"2026-05-28T21:46:39.71158Z","steps":["trace[1309495000] 'count revisions from in-memory index tree' (duration: 105.109575ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:56.826952Z","caller":"traceutil/trace.go:171","msg":"trace[516554251] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"147.130153ms","start":"2026-05-28T21:46:56.679788Z","end":"2026-05-28T21:46:56.826918Z","steps":["trace[516554251] 'process raft request' (duration: 93.52808ms)","trace[516554251] 'compare' (duration: 53.067413ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:56.827375Z","caller":"traceutil/trace.go:171","msg":"trace[636112357] transaction","detail":"{read_only:false; response_revision:3811; number_of_response:1; }","duration":"146.273146ms","start":"2026-05-28T21:46:56.681086Z","end":"2026-05-28T21:46:56.827359Z","steps":["trace[636112357] 'process raft request' (duration: 145.908629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:57.16253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.572613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:57.162858Z","caller":"traceutil/trace.go:171","msg":"trace[1123051923] linearizableReadLoop","detail":"{readStateIndex:3962; appliedIndex:3961; }","duration":"202.631094ms","start":"2026-05-28T21:46:56.960204Z","end":"2026-05-28T21:46:57.162835Z","steps":["trace[1123051923] 'read index received' (duration: 64.822047ms)","trace[1123051923] 'applied index is now lower than readState.Index' (duration: 137.807407ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:57.162804Z","caller":"traceutil/trace.go:171","msg":"trace[764383286] transaction","detail":"{read_only:false; response_revision:3812; number_of_response:1; }","duration":"276.589259ms","start":"2026-05-28T21:46:56.886178Z","end":"2026-05-28T21:46:57.162767Z","steps":["trace[764383286] 'process raft request' (duration: 138.69785ms)","trace[764383286] 'compare' (duration: 137.357459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:57.163118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.906609ms","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-05-28T21:46:57.163183Z","caller":"traceutil/trace.go:171","msg":"trace[1144916662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3812; }","duration":"202.995621ms","start":"2026-05-28T21:46:56.960171Z","end":"2026-05-28T21:46:57.163167Z","steps":["trace[1144916662] 'agreement among raft nodes before linearized reading' (duration: 202.769647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:57.163822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.295814ms","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-05-28T21:46:57.163858Z","caller":"traceutil/trace.go:171","msg":"trace[2087421147] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:3813; }","duration":"128.355995ms","start":"2026-05-28T21:46:57.035484Z","end":"2026-05-28T21:46:57.16384Z","steps":["trace[2087421147] 'agreement among raft nodes before linearized reading' (duration: 128.297444ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:57.163945Z","caller":"traceutil/trace.go:171","msg":"trace[482990341] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"189.725256ms","start":"2026-05-28T21:46:56.974199Z","end":"2026-05-28T21:46:57.163924Z","steps":["trace[482990341] 'process raft request' (duration: 189.268457ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300274Z","caller":"traceutil/trace.go:171","msg":"trace[761321117] transaction","detail":"{read_only:false; response_revision:4219; number_of_response:1; }","duration":"128.731253ms","start":"2026-05-28T21:47:39.171493Z","end":"2026-05-28T21:47:39.300225Z","steps":["trace[761321117] 'process raft request' (duration: 99.693465ms)","trace[761321117] 'compare' (duration: 28.810344ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:39.300691Z","caller":"traceutil/trace.go:171","msg":"trace[475860649] transaction","detail":"{read_only:false; response_revision:4222; number_of_response:1; }","duration":"124.841365ms","start":"2026-05-28T21:47:39.175841Z","end":"2026-05-28T21:47:39.300682Z","steps":["trace[475860649] 'process raft request' (duration: 124.376197ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300727Z","caller":"traceutil/trace.go:171","msg":"trace[1330478572] transaction","detail":"{read_only:false; response_revision:4225; number_of_response:1; }","duration":"115.087154ms","start":"2026-05-28T21:47:39.185631Z","end":"2026-05-28T21:47:39.300718Z","steps":["trace[1330478572] 'process raft request' (duration: 114.708407ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300809Z","caller":"traceutil/trace.go:171","msg":"trace[1637301253] transaction","detail":"{read_only:false; response_revision:4220; number_of_response:1; }","duration":"128.628861ms","start":"2026-05-28T21:47:39.172173Z","end":"2026-05-28T21:47:39.300802Z","steps":["trace[1637301253] 'process raft request' (duration: 127.97799ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300858Z","caller":"traceutil/trace.go:171","msg":"trace[1095523344] transaction","detail":"{read_only:false; response_revision:4226; number_of_response:1; }","duration":"115.115894ms","start":"2026-05-28T21:47:39.185734Z","end":"2026-05-28T21:47:39.300849Z","steps":["trace[1095523344] 'process raft request' (duration: 114.651366ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300899Z","caller":"traceutil/trace.go:171","msg":"trace[1205208591] transaction","detail":"{read_only:false; response_revision:4221; number_of_response:1; }","duration":"125.332054ms","start":"2026-05-28T21:47:39.17556Z","end":"2026-05-28T21:47:39.300892Z","steps":["trace[1205208591] 'process raft request' (duration: 124.625232ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.300955Z","caller":"traceutil/trace.go:171","msg":"trace[1752234211] transaction","detail":"{read_only:false; response_revision:4223; number_of_response:1; }","duration":"124.959807ms","start":"2026-05-28T21:47:39.175968Z","end":"2026-05-28T21:47:39.300927Z","steps":["trace[1752234211] 'process raft request' (duration: 124.290346ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.30105Z","caller":"traceutil/trace.go:171","msg":"trace[1025520076] transaction","detail":"{read_only:false; response_revision:4227; number_of_response:1; }","duration":"111.648544ms","start":"2026-05-28T21:47:39.189393Z","end":"2026-05-28T21:47:39.301041Z","steps":["trace[1025520076] 'process raft request' (duration: 111.034874ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.30067Z","caller":"traceutil/trace.go:171","msg":"trace[1838520293] transaction","detail":"{read_only:false; response_revision:4231; number_of_response:1; }","duration":"107.024363ms","start":"2026-05-28T21:47:39.193594Z","end":"2026-05-28T21:47:39.300619Z","steps":["trace[1838520293] 'process raft request' (duration: 106.960562ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.30114Z","caller":"traceutil/trace.go:171","msg":"trace[121646166] transaction","detail":"{read_only:false; response_revision:4224; number_of_response:1; }","duration":"115.649534ms","start":"2026-05-28T21:47:39.18548Z","end":"2026-05-28T21:47:39.301129Z","steps":["trace[121646166] 'process raft request' (duration: 114.810939ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301244Z","caller":"traceutil/trace.go:171","msg":"trace[456544185] transaction","detail":"{read_only:false; response_revision:4228; number_of_response:1; }","duration":"111.679174ms","start":"2026-05-28T21:47:39.189555Z","end":"2026-05-28T21:47:39.301234Z","steps":["trace[456544185] 'process raft request' (duration: 110.898951ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301343Z","caller":"traceutil/trace.go:171","msg":"trace[1572351979] transaction","detail":"{read_only:false; response_revision:4229; number_of_response:1; }","duration":"111.739066ms","start":"2026-05-28T21:47:39.189597Z","end":"2026-05-28T21:47:39.301336Z","steps":["trace[1572351979] 'process raft request' (duration: 110.892102ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301354Z","caller":"traceutil/trace.go:171","msg":"trace[1167633939] transaction","detail":"{read_only:false; response_revision:4232; number_of_response:1; }","duration":"107.659513ms","start":"2026-05-28T21:47:39.193685Z","end":"2026-05-28T21:47:39.301345Z","steps":["trace[1167633939] 'process raft request' (duration: 106.89961ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301413Z","caller":"traceutil/trace.go:171","msg":"trace[1550196518] transaction","detail":"{read_only:false; response_revision:4230; number_of_response:1; }","duration":"111.692145ms","start":"2026-05-28T21:47:39.189713Z","end":"2026-05-28T21:47:39.301405Z","steps":["trace[1550196518] 'process raft request' (duration: 110.80441ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301885Z","caller":"traceutil/trace.go:171","msg":"trace[1637983294] transaction","detail":"{read_only:false; response_revision:4235; number_of_response:1; }","duration":"100.529358ms","start":"2026-05-28T21:47:39.20134Z","end":"2026-05-28T21:47:39.30187Z","steps":["trace[1637983294] 'process raft request' (duration: 100.351495ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301897Z","caller":"traceutil/trace.go:171","msg":"trace[1039724838] transaction","detail":"{read_only:false; response_revision:4233; number_of_response:1; }","duration":"108.155402ms","start":"2026-05-28T21:47:39.193717Z","end":"2026-05-28T21:47:39.301872Z","steps":["trace[1039724838] 'process raft request' (duration: 107.567552ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.301978Z","caller":"traceutil/trace.go:171","msg":"trace[97472187] transaction","detail":"{read_only:false; response_revision:4236; number_of_response:1; }","duration":"100.484527ms","start":"2026-05-28T21:47:39.201485Z","end":"2026-05-28T21:47:39.301969Z","steps":["trace[97472187] 'process raft request' (duration: 100.281094ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.302023Z","caller":"traceutil/trace.go:171","msg":"trace[1832842550] transaction","detail":"{read_only:false; response_revision:4234; number_of_response:1; }","duration":"106.036785ms","start":"2026-05-28T21:47:39.195973Z","end":"2026-05-28T21:47:39.302009Z","steps":["trace[1832842550] 'process raft request' (duration: 105.632418ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.302045Z","caller":"traceutil/trace.go:171","msg":"trace[1012177524] transaction","detail":"{read_only:false; response_revision:4237; number_of_response:1; }","duration":"100.490457ms","start":"2026-05-28T21:47:39.201546Z","end":"2026-05-28T21:47:39.302037Z","steps":["trace[1012177524] 'process raft request' (duration: 100.247163ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.302269Z","caller":"traceutil/trace.go:171","msg":"trace[1129816235] linearizableReadLoop","detail":"{readStateIndex:4390; appliedIndex:4387; }","duration":"129.1101ms","start":"2026-05-28T21:47:39.173143Z","end":"2026-05-28T21:47:39.302253Z","steps":["trace[1129816235] 'read index received' (duration: 32.038541ms)","trace[1129816235] 'applied index is now lower than readState.Index' (duration: 97.070619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:39.304593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.430501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k6ksf\" ","response":"range_response_count:1 size:8087"} {"level":"info","ts":"2026-05-28T21:47:39.305958Z","caller":"traceutil/trace.go:171","msg":"trace[1567554092] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k6ksf; range_end:; response_count:1; response_revision:4241; }","duration":"131.541652ms","start":"2026-05-28T21:47:39.173092Z","end":"2026-05-28T21:47:39.304634Z","steps":["trace[1567554092] 'agreement among raft nodes before linearized reading' (duration: 129.501127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.307867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.482394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:4756"} {"level":"info","ts":"2026-05-28T21:47:39.307921Z","caller":"traceutil/trace.go:171","msg":"trace[969526633] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4247; }","duration":"114.566015ms","start":"2026-05-28T21:47:39.193341Z","end":"2026-05-28T21:47:39.307907Z","steps":["trace[969526633] 'agreement among raft nodes before linearized reading' (duration: 114.430212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.312639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.193576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:10568"} {"level":"info","ts":"2026-05-28T21:47:39.312748Z","caller":"traceutil/trace.go:171","msg":"trace[1127439466] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4247; }","duration":"107.298728ms","start":"2026-05-28T21:47:39.205416Z","end":"2026-05-28T21:47:39.312715Z","steps":["trace[1127439466] 'agreement among raft nodes before linearized reading' (duration: 107.112784ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.313591Z","caller":"traceutil/trace.go:171","msg":"trace[1996717271] transaction","detail":"{read_only:false; response_revision:4244; number_of_response:1; }","duration":"104.086852ms","start":"2026-05-28T21:47:39.209495Z","end":"2026-05-28T21:47:39.313581Z","steps":["trace[1996717271] 'process raft request' (duration: 97.450205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.313804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.422868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:8235"} {"level":"info","ts":"2026-05-28T21:47:39.313792Z","caller":"traceutil/trace.go:171","msg":"trace[369205367] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4244; }","duration":"101.482938ms","start":"2026-05-28T21:47:39.212224Z","end":"2026-05-28T21:47:39.313707Z","steps":["trace[369205367] 'process raft request' (duration: 94.78033ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.313831Z","caller":"traceutil/trace.go:171","msg":"trace[1172590531] range","detail":"{range_begin:/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4247; }","duration":"108.451119ms","start":"2026-05-28T21:47:39.205373Z","end":"2026-05-28T21:47:39.313825Z","steps":["trace[1172590531] 'agreement among raft nodes before linearized reading' (duration: 108.374517ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:41.120999Z","caller":"traceutil/trace.go:171","msg":"trace[1516435755] linearizableReadLoop","detail":"{readStateIndex:4597; appliedIndex:4596; }","duration":"120.042323ms","start":"2026-05-28T21:47:41.000934Z","end":"2026-05-28T21:47:41.120976Z","steps":["trace[1516435755] 'read index received' (duration: 119.86712ms)","trace[1516435755] 'applied index is now lower than readState.Index' (duration: 174.313µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:41.121231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.224956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:41.121299Z","caller":"traceutil/trace.go:171","msg":"trace[1682765994] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4422; }","duration":"120.402719ms","start":"2026-05-28T21:47:41.000876Z","end":"2026-05-28T21:47:41.121279Z","steps":["trace[1682765994] 'agreement among raft nodes before linearized reading' (duration: 120.202926ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:41.12131Z","caller":"traceutil/trace.go:171","msg":"trace[601149942] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"129.801133ms","start":"2026-05-28T21:47:40.99148Z","end":"2026-05-28T21:47:41.121281Z","steps":["trace[601149942] 'process raft request' (duration: 129.372046ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:43.604743Z","caller":"traceutil/trace.go:171","msg":"trace[1003053880] linearizableReadLoop","detail":"{readStateIndex:4703; appliedIndex:4702; }","duration":"139.833958ms","start":"2026-05-28T21:47:43.464883Z","end":"2026-05-28T21:47:43.604717Z","steps":["trace[1003053880] 'read index received' (duration: 122.835833ms)","trace[1003053880] 'applied index is now lower than readState.Index' (duration: 16.997145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:43.605084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.178034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-28T21:47:43.605148Z","caller":"traceutil/trace.go:171","msg":"trace[1612517595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4528; }","duration":"140.264726ms","start":"2026-05-28T21:47:43.46487Z","end":"2026-05-28T21:47:43.605135Z","steps":["trace[1612517595] 'agreement among raft nodes before linearized reading' (duration: 140.086142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:43.605283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.509699ms","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-05-28T21:47:43.605343Z","caller":"traceutil/trace.go:171","msg":"trace[1158363124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4528; }","duration":"118.614421ms","start":"2026-05-28T21:47:43.486715Z","end":"2026-05-28T21:47:43.605329Z","steps":["trace[1158363124] 'agreement among raft nodes before linearized reading' (duration: 118.384967ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:43.604873Z","caller":"traceutil/trace.go:171","msg":"trace[368226961] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"140.527291ms","start":"2026-05-28T21:47:43.464333Z","end":"2026-05-28T21:47:43.604861Z","steps":["trace[368226961] 'process raft request' (duration: 123.389773ms)","trace[368226961] 'compare' (duration: 16.844923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:43.607409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-28T21:47:43.607516Z","caller":"traceutil/trace.go:171","msg":"trace[935441052] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4528; }","duration":"136.838655ms","start":"2026-05-28T21:47:43.470652Z","end":"2026-05-28T21:47:43.607491Z","steps":["trace[935441052] 'agreement among raft nodes before linearized reading' (duration: 134.285261ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:45.612843Z","caller":"traceutil/trace.go:171","msg":"trace[2113086963] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"124.757128ms","start":"2026-05-28T21:47:45.488061Z","end":"2026-05-28T21:47:45.612818Z","steps":["trace[2113086963] 'process raft request' (duration: 123.574468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.173083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.835948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:50.173251Z","caller":"traceutil/trace.go:171","msg":"trace[1361111642] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"523.146248ms","start":"2026-05-28T21:47:49.65007Z","end":"2026-05-28T21:47:50.173217Z","steps":["trace[1361111642] 'process raft request' (duration: 115.045235ms)","trace[1361111642] 'compare' (duration: 407.729487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:50.173356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.650042Z","time spent":"523.26557ms","remote":"127.0.0.1:55282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:50.198521Z","caller":"traceutil/trace.go:171","msg":"trace[1473772561] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4838; }","duration":"493.814973ms","start":"2026-05-28T21:47:49.704683Z","end":"2026-05-28T21:47:50.198498Z","steps":["trace[1473772561] 'read index received' (duration: 60.339986ms)","trace[1473772561] 'applied index is now lower than readState.Index' (duration: 433.474027ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:50.198634Z","caller":"traceutil/trace.go:171","msg":"trace[102730587] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"546.963925ms","start":"2026-05-28T21:47:49.651656Z","end":"2026-05-28T21:47:50.19862Z","steps":["trace[102730587] 'process raft request' (duration: 546.786342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.198745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.651645Z","time spent":"547.017946ms","remote":"127.0.0.1:55628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:50.198765Z","caller":"traceutil/trace.go:171","msg":"trace[1062994989] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"548.378701ms","start":"2026-05-28T21:47:49.650324Z","end":"2026-05-28T21:47:50.198703Z","steps":["trace[1062994989] 'process raft request' (duration: 548.007585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.198949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.650302Z","time spent":"548.542803ms","remote":"127.0.0.1:55380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:50.199017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.320572ms","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-05-28T21:47:50.199081Z","caller":"traceutil/trace.go:171","msg":"trace[1607643143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4659; }","duration":"494.391873ms","start":"2026-05-28T21:47:49.704676Z","end":"2026-05-28T21:47:50.199068Z","steps":["trace[1607643143] 'agreement among raft nodes before linearized reading' (duration: 494.21745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.199117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.70462Z","time spent":"494.488465ms","remote":"127.0.0.1:55370","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-05-28T21:47:50.227315Z","caller":"traceutil/trace.go:171","msg":"trace[1311920498] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"398.567167ms","start":"2026-05-28T21:47:49.828728Z","end":"2026-05-28T21:47:50.227295Z","steps":["trace[1311920498] 'process raft request' (duration: 398.453665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.828705Z","time spent":"398.666898ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:50.227597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.184587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-28T21:47:50.227598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.176346ms","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-05-28T21:47:50.227649Z","caller":"traceutil/trace.go:171","msg":"trace[1365608354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4660; }","duration":"339.279129ms","start":"2026-05-28T21:47:49.888357Z","end":"2026-05-28T21:47:50.227636Z","steps":["trace[1365608354] 'agreement among raft nodes before linearized reading' (duration: 339.134377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.612096ms","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-05-28T21:47:50.227688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.888332Z","time spent":"339.347721ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T21:47:50.227682Z","caller":"traceutil/trace.go:171","msg":"trace[7955851] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4660; }","duration":"488.344469ms","start":"2026-05-28T21:47:49.739315Z","end":"2026-05-28T21:47:50.22766Z","steps":["trace[7955851] 'agreement among raft nodes before linearized reading' (duration: 488.093304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.082513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-28T21:47:50.227746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.739285Z","time spent":"488.445551ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-28T21:47:50.227726Z","caller":"traceutil/trace.go:171","msg":"trace[483270629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4660; }","duration":"314.731348ms","start":"2026-05-28T21:47:49.912979Z","end":"2026-05-28T21:47:50.227711Z","steps":["trace[483270629] 'agreement among raft nodes before linearized reading' (duration: 314.474782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.020438ms","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-05-28T21:47:50.227868Z","caller":"traceutil/trace.go:171","msg":"trace[1524254999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4660; }","duration":"313.079539ms","start":"2026-05-28T21:47:49.914777Z","end":"2026-05-28T21:47:50.227857Z","steps":["trace[1524254999] 'agreement among raft nodes before linearized reading' (duration: 312.979827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.914767Z","time spent":"313.12992ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:47:50.227779Z","caller":"traceutil/trace.go:171","msg":"trace[590430353] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:4660; }","duration":"347.224035ms","start":"2026-05-28T21:47:49.880537Z","end":"2026-05-28T21:47:50.227761Z","steps":["trace[590430353] 'agreement among raft nodes before linearized reading' (duration: 346.976471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.227959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.880481Z","time spent":"347.460649ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-05-28T21:47:50.227825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.912969Z","time spent":"314.843749ms","remote":"127.0.0.1:55370","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-05-28T21:47:50.227722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.959495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:50.228142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.339858ms","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-05-28T21:47:50.228223Z","caller":"traceutil/trace.go:171","msg":"trace[395180298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4660; }","duration":"261.482583ms","start":"2026-05-28T21:47:49.966725Z","end":"2026-05-28T21:47:50.228208Z","steps":["trace[395180298] 'agreement among raft nodes before linearized reading' (duration: 260.967984ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.228305Z","caller":"traceutil/trace.go:171","msg":"trace[430104459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4660; }","duration":"315.531501ms","start":"2026-05-28T21:47:49.912694Z","end":"2026-05-28T21:47:50.228225Z","steps":["trace[430104459] 'agreement among raft nodes before linearized reading' (duration: 314.88423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.229418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.912666Z","time spent":"315.729975ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T21:47:50.30751Z","caller":"traceutil/trace.go:171","msg":"trace[988669777] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"103.566325ms","start":"2026-05-28T21:47:50.20389Z","end":"2026-05-28T21:47:50.307456Z","steps":["trace[988669777] 'process raft request' (duration: 69.345299ms)","trace[988669777] 'compare' (duration: 33.806859ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:50.30769Z","caller":"traceutil/trace.go:171","msg":"trace[1284133887] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"100.432342ms","start":"2026-05-28T21:47:50.207232Z","end":"2026-05-28T21:47:50.307664Z","steps":["trace[1284133887] 'process raft request' (duration: 99.925313ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.389653Z","caller":"traceutil/trace.go:171","msg":"trace[1811884428] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"157.484569ms","start":"2026-05-28T21:47:50.23214Z","end":"2026-05-28T21:47:50.389624Z","steps":["trace[1811884428] 'process raft request' (duration: 157.287146ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.389841Z","caller":"traceutil/trace.go:171","msg":"trace[28690592] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"157.57545ms","start":"2026-05-28T21:47:50.232227Z","end":"2026-05-28T21:47:50.389802Z","steps":["trace[28690592] 'process raft request' (duration: 157.316556ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.390595Z","caller":"traceutil/trace.go:171","msg":"trace[1104103833] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"157.955308ms","start":"2026-05-28T21:47:50.232621Z","end":"2026-05-28T21:47:50.390576Z","steps":["trace[1104103833] 'process raft request' (duration: 157.916437ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.390805Z","caller":"traceutil/trace.go:171","msg":"trace[1641837642] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"158.302363ms","start":"2026-05-28T21:47:50.232468Z","end":"2026-05-28T21:47:50.39077Z","steps":["trace[1641837642] 'process raft request' (duration: 158.026429ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:50.390603Z","caller":"traceutil/trace.go:171","msg":"trace[1499227783] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"158.300614ms","start":"2026-05-28T21:47:50.232281Z","end":"2026-05-28T21:47:50.390581Z","steps":["trace[1499227783] 'process raft request' (duration: 157.298596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.908695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.075715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:50.908928Z","caller":"traceutil/trace.go:171","msg":"trace[1451880824] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"411.33364ms","start":"2026-05-28T21:47:50.497578Z","end":"2026-05-28T21:47:50.908911Z","steps":["trace[1451880824] 'process raft request' (duration: 411.262199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.908997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:50.497562Z","time spent":"411.407241ms","remote":"127.0.0.1:55202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:47:50.909056Z","caller":"traceutil/trace.go:171","msg":"trace[90657470] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4850; }","duration":"412.316147ms","start":"2026-05-28T21:47:50.496708Z","end":"2026-05-28T21:47:50.909024Z","steps":["trace[90657470] 'read index received' (duration: 59.821035ms)","trace[90657470] 'applied index is now lower than readState.Index' (duration: 352.491382ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:50.909079Z","caller":"traceutil/trace.go:171","msg":"trace[1076054415] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"518.024859ms","start":"2026-05-28T21:47:50.391012Z","end":"2026-05-28T21:47:50.909037Z","steps":["trace[1076054415] 'process raft request' (duration: 165.500297ms)","trace[1076054415] 'compare' (duration: 351.853681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:50.909284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.574851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k6ksf\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-05-28T21:47:50.909329Z","caller":"traceutil/trace.go:171","msg":"trace[2069260805] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k6ksf; range_end:; response_count:1; response_revision:4669; }","duration":"412.665223ms","start":"2026-05-28T21:47:50.496651Z","end":"2026-05-28T21:47:50.909316Z","steps":["trace[2069260805] 'agreement among raft nodes before linearized reading' (duration: 412.459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.909361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:50.496625Z","time spent":"412.726834ms","remote":"127.0.0.1:55286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9781,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-k6ksf\" "} {"level":"warn","ts":"2026-05-28T21:47:50.909524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:50.390991Z","time spent":"518.275733ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:50.910406Z","caller":"traceutil/trace.go:171","msg":"trace[436465135] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"310.761606ms","start":"2026-05-28T21:47:50.599629Z","end":"2026-05-28T21:47:50.91039Z","steps":["trace[436465135] 'process raft request' (duration: 310.603004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:50.910494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:50.599606Z","time spent":"310.845858ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:57.463636Z","caller":"traceutil/trace.go:171","msg":"trace[431011735] linearizableReadLoop","detail":"{readStateIndex:4908; appliedIndex:4905; }","duration":"729.926872ms","start":"2026-05-28T21:47:56.733683Z","end":"2026-05-28T21:47:57.46361Z","steps":["trace[431011735] 'read index received' (duration: 7.889013ms)","trace[431011735] 'applied index is now lower than readState.Index' (duration: 722.035979ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:57.463718Z","caller":"traceutil/trace.go:171","msg":"trace[606255942] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"732.647309ms","start":"2026-05-28T21:47:56.731036Z","end":"2026-05-28T21:47:57.463684Z","steps":["trace[606255942] 'process raft request' (duration: 732.352744ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.463671Z","caller":"traceutil/trace.go:171","msg":"trace[779521983] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"730.104696ms","start":"2026-05-28T21:47:56.733531Z","end":"2026-05-28T21:47:57.463636Z","steps":["trace[779521983] 'process raft request' (duration: 730.005474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.463875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.733519Z","time spent":"730.277159ms","remote":"127.0.0.1:55202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:47:57.463872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.731018Z","time spent":"732.773621ms","remote":"127.0.0.1:55380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:47:57.464009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.298139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130562"} {"level":"info","ts":"2026-05-28T21:47:57.464066Z","caller":"traceutil/trace.go:171","msg":"trace[274583780] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4723; }","duration":"730.37689ms","start":"2026-05-28T21:47:56.733677Z","end":"2026-05-28T21:47:57.464054Z","steps":["trace[274583780] 'agreement among raft nodes before linearized reading' (duration: 730.065545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.46411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.733644Z","time spent":"730.457162ms","remote":"127.0.0.1:55184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130586,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-28T21:47:57.476197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.409101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:57.47629Z","caller":"traceutil/trace.go:171","msg":"trace[718018123] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4726; }","duration":"572.549093ms","start":"2026-05-28T21:47:56.903715Z","end":"2026-05-28T21:47:57.476264Z","steps":["trace[718018123] 'agreement among raft nodes before linearized reading' (duration: 572.396951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.476346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.903693Z","time spent":"572.639325ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:57.476626Z","caller":"traceutil/trace.go:171","msg":"trace[1481287945] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"545.910592ms","start":"2026-05-28T21:47:56.930669Z","end":"2026-05-28T21:47:57.47658Z","steps":["trace[1481287945] 'process raft request' (duration: 545.206971ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.476746Z","caller":"traceutil/trace.go:171","msg":"trace[1337057162] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"521.931195ms","start":"2026-05-28T21:47:56.954793Z","end":"2026-05-28T21:47:57.476724Z","steps":["trace[1337057162] 'process raft request' (duration: 521.219743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.476774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.585713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:57.476811Z","caller":"traceutil/trace.go:171","msg":"trace[181701452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4726; }","duration":"513.652115ms","start":"2026-05-28T21:47:56.963148Z","end":"2026-05-28T21:47:57.4768Z","steps":["trace[181701452] 'agreement among raft nodes before linearized reading' (duration: 513.599534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.476825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.93065Z","time spent":"546.062305ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:57.476874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.954743Z","time spent":"522.045627ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:57.476891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.85387ms","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-05-28T21:47:57.476925Z","caller":"traceutil/trace.go:171","msg":"trace[176295351] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4726; }","duration":"568.908832ms","start":"2026-05-28T21:47:56.908005Z","end":"2026-05-28T21:47:57.476914Z","steps":["trace[176295351] 'agreement among raft nodes before linearized reading' (duration: 568.856111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.476953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.907993Z","time spent":"568.952632ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":9,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:57.476839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:56.963136Z","time spent":"513.697196ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T21:47:57.890188Z","caller":"traceutil/trace.go:171","msg":"trace[475412069] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"210.699466ms","start":"2026-05-28T21:47:57.679467Z","end":"2026-05-28T21:47:57.890166Z","steps":["trace[475412069] 'process raft request' (duration: 172.489509ms)","trace[475412069] 'compare' (duration: 38.020774ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:57.890415Z","caller":"traceutil/trace.go:171","msg":"trace[1308557498] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"210.899429ms","start":"2026-05-28T21:47:57.679476Z","end":"2026-05-28T21:47:57.890376Z","steps":["trace[1308557498] 'process raft request' (duration: 210.667636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:57.890597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.057692ms","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-05-28T21:47:57.890632Z","caller":"traceutil/trace.go:171","msg":"trace[1381091543] 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:4743; }","duration":"211.137524ms","start":"2026-05-28T21:47:57.679487Z","end":"2026-05-28T21:47:57.890625Z","steps":["trace[1381091543] 'agreement among raft nodes before linearized reading' (duration: 211.010041ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.890704Z","caller":"traceutil/trace.go:171","msg":"trace[1033640826] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"211.142803ms","start":"2026-05-28T21:47:57.679535Z","end":"2026-05-28T21:47:57.890678Z","steps":["trace[1033640826] 'process raft request' (duration: 210.763467ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.890768Z","caller":"traceutil/trace.go:171","msg":"trace[723282395] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"203.976073ms","start":"2026-05-28T21:47:57.686785Z","end":"2026-05-28T21:47:57.890761Z","steps":["trace[723282395] 'process raft request' (duration: 203.614477ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.890459Z","caller":"traceutil/trace.go:171","msg":"trace[556533423] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4921; }","duration":"210.918709ms","start":"2026-05-28T21:47:57.679522Z","end":"2026-05-28T21:47:57.890441Z","steps":["trace[556533423] 'read index received' (duration: 20.843872ms)","trace[556533423] 'applied index is now lower than readState.Index' (duration: 190.074037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:57.890907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.586145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-28T21:47:57.890924Z","caller":"traceutil/trace.go:171","msg":"trace[1357260605] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4743; }","duration":"200.633986ms","start":"2026-05-28T21:47:57.690286Z","end":"2026-05-28T21:47:57.89092Z","steps":["trace[1357260605] 'agreement among raft nodes before linearized reading' (duration: 200.580756ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.890926Z","caller":"traceutil/trace.go:171","msg":"trace[1457481857] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"209.783101ms","start":"2026-05-28T21:47:57.681134Z","end":"2026-05-28T21:47:57.890917Z","steps":["trace[1457481857] 'process raft request' (duration: 209.232362ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:57.891135Z","caller":"traceutil/trace.go:171","msg":"trace[1452720305] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"210.104087ms","start":"2026-05-28T21:47:57.68102Z","end":"2026-05-28T21:47:57.891124Z","steps":["trace[1452720305] 'process raft request' (duration: 209.316144ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:58.340675Z","caller":"traceutil/trace.go:171","msg":"trace[716154935] linearizableReadLoop","detail":"{readStateIndex:4943; appliedIndex:4942; }","duration":"116.345206ms","start":"2026-05-28T21:47:58.2243Z","end":"2026-05-28T21:47:58.340645Z","steps":["trace[716154935] 'read index received' (duration: 115.838976ms)","trace[716154935] 'applied index is now lower than readState.Index' (duration: 505.02µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:58.340815Z","caller":"traceutil/trace.go:171","msg":"trace[330113045] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"120.689299ms","start":"2026-05-28T21:47:58.220111Z","end":"2026-05-28T21:47:58.340801Z","steps":["trace[330113045] 'process raft request' (duration: 120.011727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:58.341083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.747152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:58.341138Z","caller":"traceutil/trace.go:171","msg":"trace[1003303326] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4758; }","duration":"116.863205ms","start":"2026-05-28T21:47:58.224258Z","end":"2026-05-28T21:47:58.341121Z","steps":["trace[1003303326] 'agreement among raft nodes before linearized reading' (duration: 116.723832ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:58.358245Z","caller":"traceutil/trace.go:171","msg":"trace[726130773] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"125.929268ms","start":"2026-05-28T21:47:58.232292Z","end":"2026-05-28T21:47:58.358221Z","steps":["trace[726130773] 'process raft request' (duration: 125.660143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:59.429536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.186231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:59.429807Z","caller":"traceutil/trace.go:171","msg":"trace[1668958845] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"312.466479ms","start":"2026-05-28T21:47:59.117291Z","end":"2026-05-28T21:47:59.429758Z","steps":["trace[1668958845] 'process raft request' (duration: 87.939893ms)","trace[1668958845] 'compare' (duration: 223.744853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:59.430055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:59.117263Z","time spent":"312.697505ms","remote":"127.0.0.1:55202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:48:01.009853Z","caller":"traceutil/trace.go:171","msg":"trace[30563961] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"180.455598ms","start":"2026-05-28T21:48:00.829373Z","end":"2026-05-28T21:48:01.009828Z","steps":["trace[30563961] 'process raft request' (duration: 180.054961ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:06.628172Z","caller":"traceutil/trace.go:171","msg":"trace[1269144978] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"147.875815ms","start":"2026-05-28T21:48:06.480278Z","end":"2026-05-28T21:48:06.628154Z","steps":["trace[1269144978] 'process raft request' (duration: 145.144489ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:06.628543Z","caller":"traceutil/trace.go:171","msg":"trace[1613934152] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"144.978577ms","start":"2026-05-28T21:48:06.483116Z","end":"2026-05-28T21:48:06.628094Z","steps":["trace[1613934152] 'read index received' (duration: 142.319532ms)","trace[1613934152] 'applied index is now lower than readState.Index' (duration: 2.657905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:48:06.628484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.248231ms","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-05-28T21:48:06.628803Z","caller":"traceutil/trace.go:171","msg":"trace[1979005277] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4911; }","duration":"145.684738ms","start":"2026-05-28T21:48:06.483094Z","end":"2026-05-28T21:48:06.628779Z","steps":["trace[1979005277] 'agreement among raft nodes before linearized reading' (duration: 145.162659ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:06.635093Z","caller":"traceutil/trace.go:171","msg":"trace[940295426] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"151.889872ms","start":"2026-05-28T21:48:06.483192Z","end":"2026-05-28T21:48:06.635082Z","steps":["trace[940295426] 'process raft request' (duration: 151.740198ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:06.635135Z","caller":"traceutil/trace.go:171","msg":"trace[1993247642] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"151.080098ms","start":"2026-05-28T21:48:06.484047Z","end":"2026-05-28T21:48:06.635127Z","steps":["trace[1993247642] 'process raft request' (duration: 150.964165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:06.635104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.977956ms","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-05-28T21:48:06.636187Z","caller":"traceutil/trace.go:171","msg":"trace[1007087079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4913; }","duration":"151.20157ms","start":"2026-05-28T21:48:06.48409Z","end":"2026-05-28T21:48:06.635292Z","steps":["trace[1007087079] 'agreement among raft nodes before linearized reading' (duration: 150.947796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:10.62764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.218054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:48:10.627712Z","caller":"traceutil/trace.go:171","msg":"trace[1637129314] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4946; }","duration":"253.387606ms","start":"2026-05-28T21:48:10.374308Z","end":"2026-05-28T21:48:10.627696Z","steps":["trace[1637129314] 'count revisions from in-memory index tree' (duration: 253.120742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:10.627983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.846759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:48:10.628127Z","caller":"traceutil/trace.go:171","msg":"trace[547991911] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"200.958808ms","start":"2026-05-28T21:48:10.427151Z","end":"2026-05-28T21:48:10.62811Z","steps":["trace[547991911] 'read index received' (duration: 32.785983ms)","trace[547991911] 'applied index is now lower than readState.Index' (duration: 168.171315ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:10.628235Z","caller":"traceutil/trace.go:171","msg":"trace[1422534657] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"232.842015ms","start":"2026-05-28T21:48:10.395298Z","end":"2026-05-28T21:48:10.62814Z","steps":["trace[1422534657] 'process raft request' (duration: 64.734702ms)","trace[1422534657] 'compare' (duration: 167.586315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:48:10.628303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.155611ms","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-05-28T21:48:10.628402Z","caller":"traceutil/trace.go:171","msg":"trace[1549506767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4947; }","duration":"201.292503ms","start":"2026-05-28T21:48:10.427098Z","end":"2026-05-28T21:48:10.62839Z","steps":["trace[1549506767] 'agreement among raft nodes before linearized reading' (duration: 201.08833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:05.271476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.767348ms","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-05-28T21:49:05.27222Z","caller":"traceutil/trace.go:171","msg":"trace[1458672295] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5414; }","duration":"103.5448ms","start":"2026-05-28T21:49:05.168647Z","end":"2026-05-28T21:49:05.272192Z","steps":["trace[1458672295] 'agreement among raft nodes before linearized reading' (duration: 102.778668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.814755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532699729378180,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:49:13.957744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.049451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:49:13.957823Z","caller":"traceutil/trace.go:171","msg":"trace[1460301381] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"894.800439ms","start":"2026-05-28T21:49:13.063009Z","end":"2026-05-28T21:49:13.957809Z","steps":["trace[1460301381] 'process raft request' (duration: 183.607666ms)","trace[1460301381] 'compare' (duration: 710.701836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:49:13.957865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.062989Z","time spent":"894.85622ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:49:13.959769Z","caller":"traceutil/trace.go:171","msg":"trace[1795789605] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"817.827198ms","start":"2026-05-28T21:49:13.141926Z","end":"2026-05-28T21:49:13.959753Z","steps":["trace[1795789605] 'process raft request' (duration: 817.762467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.959943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.141908Z","time spent":"817.909569ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5982,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:49:13.962557Z","caller":"traceutil/trace.go:171","msg":"trace[413632186] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5680; }","duration":"648.014722ms","start":"2026-05-28T21:49:13.314522Z","end":"2026-05-28T21:49:13.962536Z","steps":["trace[413632186] 'read index received' (duration: 645.135878ms)","trace[413632186] 'applied index is now lower than readState.Index' (duration: 2.877684ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:13.962858Z","caller":"traceutil/trace.go:171","msg":"trace[82131567] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"310.875812ms","start":"2026-05-28T21:49:13.651972Z","end":"2026-05-28T21:49:13.962848Z","steps":["trace[82131567] 'process raft request' (duration: 310.417794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.962951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.651947Z","time spent":"310.949253ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:49:13.963069Z","caller":"traceutil/trace.go:171","msg":"trace[191261896] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"128.724209ms","start":"2026-05-28T21:49:13.834339Z","end":"2026-05-28T21:49:13.963063Z","steps":["trace[191261896] 'process raft request' (duration: 128.129839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.963261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.744474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T21:49:13.963292Z","caller":"traceutil/trace.go:171","msg":"trace[1626818641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5478; }","duration":"648.804185ms","start":"2026-05-28T21:49:13.314481Z","end":"2026-05-28T21:49:13.963285Z","steps":["trace[1626818641] 'agreement among raft nodes before linearized reading' (duration: 648.713314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.963311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.314466Z","time spent":"648.840146ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:49:13.963421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.252631ms","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-05-28T21:49:13.963444Z","caller":"traceutil/trace.go:171","msg":"trace[1451860095] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5478; }","duration":"553.310762ms","start":"2026-05-28T21:49:13.410128Z","end":"2026-05-28T21:49:13.963438Z","steps":["trace[1451860095] 'agreement among raft nodes before linearized reading' (duration: 553.255221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.963462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.410109Z","time spent":"553.348623ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T21:49:13.963715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.965245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:5997"} {"level":"info","ts":"2026-05-28T21:49:13.96374Z","caller":"traceutil/trace.go:171","msg":"trace[1512298268] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:5478; }","duration":"273.048776ms","start":"2026-05-28T21:49:13.690686Z","end":"2026-05-28T21:49:13.963735Z","steps":["trace[1512298268] 'agreement among raft nodes before linearized reading' (duration: 273.010245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.963912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.006211ms","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-05-28T21:49:13.963937Z","caller":"traceutil/trace.go:171","msg":"trace[1168181984] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5478; }","duration":"278.066292ms","start":"2026-05-28T21:49:13.685865Z","end":"2026-05-28T21:49:13.963931Z","steps":["trace[1168181984] 'agreement among raft nodes before linearized reading' (duration: 278.027201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.964049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.693526ms","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-05-28T21:49:13.964071Z","caller":"traceutil/trace.go:171","msg":"trace[1276183067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5478; }","duration":"549.736637ms","start":"2026-05-28T21:49:13.414329Z","end":"2026-05-28T21:49:13.964066Z","steps":["trace[1276183067] 'agreement among raft nodes before linearized reading' (duration: 549.680156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.964087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.414319Z","time spent":"549.764797ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:49:13.964202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.858089ms","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-05-28T21:49:13.964223Z","caller":"traceutil/trace.go:171","msg":"trace[632028462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5478; }","duration":"549.900299ms","start":"2026-05-28T21:49:13.414318Z","end":"2026-05-28T21:49:13.964218Z","steps":["trace[632028462] 'agreement among raft nodes before linearized reading' (duration: 549.836918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.964241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.414307Z","time spent":"549.92915ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:49:16.621403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2830} {"level":"info","ts":"2026-05-28T21:49:16.709746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2830,"took":"86.682175ms","hash":1148521565,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-28T21:49:16.709846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1148521565,"revision":2830,"compact-revision":-1} {"level":"warn","ts":"2026-05-28T21:51:43.020639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532699729380757,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:51:43.107241Z","caller":"traceutil/trace.go:171","msg":"trace[1021735571] transaction","detail":"{read_only:false; response_revision:6505; number_of_response:1; }","duration":"612.008612ms","start":"2026-05-28T21:51:42.495207Z","end":"2026-05-28T21:51:43.107215Z","steps":["trace[1021735571] 'process raft request' (duration: 611.803589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.107417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.495185Z","time spent":"612.146904ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:51:43.108848Z","caller":"traceutil/trace.go:171","msg":"trace[134747216] transaction","detail":"{read_only:false; response_revision:6506; number_of_response:1; }","duration":"612.736751ms","start":"2026-05-28T21:51:42.496086Z","end":"2026-05-28T21:51:43.108823Z","steps":["trace[134747216] 'process raft request' (duration: 612.606849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.109109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.44774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-05-28T21:51:43.109195Z","caller":"traceutil/trace.go:171","msg":"trace[1215938833] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6506; }","duration":"589.566261ms","start":"2026-05-28T21:51:42.519612Z","end":"2026-05-28T21:51:43.109178Z","steps":["trace[1215938833] 'agreement among raft nodes before linearized reading' (duration: 589.362419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.109247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.519597Z","time spent":"589.637113ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4883,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-28T21:51:43.111754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.496055Z","time spent":"612.871802ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:51:43.112054Z","caller":"traceutil/trace.go:171","msg":"trace[1712857405] linearizableReadLoop","detail":"{readStateIndex:6748; appliedIndex:6746; }","duration":"589.124566ms","start":"2026-05-28T21:51:42.519656Z","end":"2026-05-28T21:51:43.108781Z","steps":["trace[1712857405] 'read index received' (duration: 587.330932ms)","trace[1712857405] 'applied index is now lower than readState.Index' (duration: 1.792784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:51:43.112872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.672237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-28T21:51:43.112938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.384109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-05-28T21:51:43.112972Z","caller":"traceutil/trace.go:171","msg":"trace[1474141615] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6506; }","duration":"438.46011ms","start":"2026-05-28T21:51:42.674503Z","end":"2026-05-28T21:51:43.112963Z","steps":["trace[1474141615] 'agreement among raft nodes before linearized reading' (duration: 438.352908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.112975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.976534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T21:51:43.112999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.674482Z","time spent":"438.510241ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4883,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-05-28T21:51:43.11302Z","caller":"traceutil/trace.go:171","msg":"trace[874333246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6506; }","duration":"417.037896ms","start":"2026-05-28T21:51:42.695969Z","end":"2026-05-28T21:51:43.113006Z","steps":["trace[874333246] 'agreement among raft nodes before linearized reading' (duration: 416.932924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.113061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.695958Z","time spent":"417.094866ms","remote":"127.0.0.1:55370","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-05-28T21:51:43.113269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.333829ms","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-05-28T21:51:43.113269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.786008ms","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-05-28T21:51:43.113295Z","caller":"traceutil/trace.go:171","msg":"trace[1019414221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6506; }","duration":"425.38219ms","start":"2026-05-28T21:51:42.687906Z","end":"2026-05-28T21:51:43.113289Z","steps":["trace[1019414221] 'agreement among raft nodes before linearized reading' (duration: 425.313619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.113317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.687895Z","time spent":"425.417271ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T21:51:43.113329Z","caller":"traceutil/trace.go:171","msg":"trace[313864662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6506; }","duration":"439.88283ms","start":"2026-05-28T21:51:42.673427Z","end":"2026-05-28T21:51:43.11331Z","steps":["trace[313864662] 'agreement among raft nodes before linearized reading' (duration: 439.638486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.113355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.022653ms","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":"warn","ts":"2026-05-28T21:51:43.113378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.673407Z","time spent":"439.96235ms","remote":"127.0.0.1:55370","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-05-28T21:51:43.11339Z","caller":"traceutil/trace.go:171","msg":"trace[1540769149] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6506; }","duration":"427.077764ms","start":"2026-05-28T21:51:42.686301Z","end":"2026-05-28T21:51:43.113379Z","steps":["trace[1540769149] 'agreement among raft nodes before linearized reading' (duration: 426.991772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.113419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.686289Z","time spent":"427.121945ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T21:51:43.113486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.256752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:51:43.113519Z","caller":"traceutil/trace.go:171","msg":"trace[1699469813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6506; }","duration":"148.321412ms","start":"2026-05-28T21:51:42.96519Z","end":"2026-05-28T21:51:43.113511Z","steps":["trace[1699469813] 'agreement among raft nodes before linearized reading' (duration: 148.279042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.112872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.004769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T21:51:43.11379Z","caller":"traceutil/trace.go:171","msg":"trace[253271946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6506; }","duration":"418.950631ms","start":"2026-05-28T21:51:42.694831Z","end":"2026-05-28T21:51:43.113781Z","steps":["trace[253271946] 'agreement among raft nodes before linearized reading' (duration: 417.924057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.113815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.694819Z","time spent":"418.987821ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:51:43.11405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.53519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:51:43.114089Z","caller":"traceutil/trace.go:171","msg":"trace[658208901] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:6506; }","duration":"348.596812ms","start":"2026-05-28T21:51:42.765484Z","end":"2026-05-28T21:51:43.114081Z","steps":["trace[658208901] 'agreement among raft nodes before linearized reading' (duration: 348.540201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.114111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.765473Z","time spent":"348.629292ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:51:43.112932Z","caller":"traceutil/trace.go:171","msg":"trace[1143334821] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6506; }","duration":"198.756937ms","start":"2026-05-28T21:51:42.914158Z","end":"2026-05-28T21:51:43.112915Z","steps":["trace[1143334821] 'agreement among raft nodes before linearized reading' (duration: 198.622045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.112959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.404961ms","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-05-28T21:51:43.115205Z","caller":"traceutil/trace.go:171","msg":"trace[1556899371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6506; }","duration":"438.677624ms","start":"2026-05-28T21:51:42.676512Z","end":"2026-05-28T21:51:43.115189Z","steps":["trace[1556899371] 'agreement among raft nodes before linearized reading' (duration: 436.303061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.115354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.676505Z","time spent":"438.803545ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T21:51:43.112737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.697485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:51:43.115731Z","caller":"traceutil/trace.go:171","msg":"trace[300382844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6506; }","duration":"587.743467ms","start":"2026-05-28T21:51:42.527976Z","end":"2026-05-28T21:51:43.11572Z","steps":["trace[300382844] 'agreement among raft nodes before linearized reading' (duration: 584.711675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:43.116012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:42.527969Z","time spent":"587.784988ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T21:54:16.627266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5515} {"level":"info","ts":"2026-05-28T21:54:16.735317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5515,"took":"106.416578ms","hash":274094042,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-28T21:54:16.735427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":274094042,"revision":5515,"compact-revision":2830}