{"level":"warn","ts":"2026-06-23T18:37:33.234135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:37:33.234232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-23T18:37:33.234304Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:37:33.234317Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-06-23T18:37:33.234348Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-23T18:37:33.235Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-06-23T18:37:33.235076Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-23T18:37:33.240079Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.817262ms"} {"level":"info","ts":"2026-06-23T18:37:33.250818Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-06-23T18:37:33.250977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T18:37:33.251026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-06-23T18:37:33.251056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T18:37:33.251077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-06-23T18:37:33.251146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-06-23T18:37:33.263816Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T18:37:33.270312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T18:37:33.271494Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-23T18:37:33.27483Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T18:37:33.27498Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T18:37:33.275158Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-23T18:37:33.275279Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-23T18:37:33.275296Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-23T18:37:33.277707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-06-23T18:37:33.277799Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-06-23T18:37:33.279105Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-23T18:37:33.279305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T18:37:33.279337Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T18:37:33.279443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-06-23T18:37:33.279462Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-06-23T18:37:34.152339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T18:37:34.152398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T18:37:34.152431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-06-23T18:37:34.152451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-06-23T18:37:34.152462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-06-23T18:37:34.152485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-06-23T18:37:34.152505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-06-23T18:37:34.15377Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:37:34.154563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:37:34.154586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:37:34.154559Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T18:37:34.154801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T18:37:34.154836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T18:37:34.155161Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:37:34.155896Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:37:34.155961Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:37:34.156982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-06-23T18:37:34.159472Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T18:37:58.261653Z","caller":"traceutil/trace.go:171","msg":"trace[969408430] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"303.654273ms","start":"2026-06-23T18:37:57.957977Z","end":"2026-06-23T18:37:58.261631Z","steps":["trace[969408430] 'read index received' (duration: 303.474855ms)","trace[969408430] 'applied index is now lower than readState.Index' (duration: 178.548µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:37:58.261957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.280905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-23T18:37:58.262012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.607261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-23T18:37:58.262075Z","caller":"traceutil/trace.go:171","msg":"trace[1367635771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"167.463433ms","start":"2026-06-23T18:37:58.094597Z","end":"2026-06-23T18:37:58.26206Z","steps":["trace[1367635771] 'agreement among raft nodes before linearized reading' (duration: 167.207561ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:37:58.262103Z","caller":"traceutil/trace.go:171","msg":"trace[199818209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"113.742177ms","start":"2026-06-23T18:37:58.14834Z","end":"2026-06-23T18:37:58.262082Z","steps":["trace[199818209] 'agreement among raft nodes before linearized reading' (duration: 113.506336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:37:58.261961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.615094ms","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-06-23T18:37:58.262195Z","caller":"traceutil/trace.go:171","msg":"trace[761261342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"116.882865ms","start":"2026-06-23T18:37:58.14529Z","end":"2026-06-23T18:37:58.262173Z","steps":["trace[761261342] 'agreement among raft nodes before linearized reading' (duration: 116.550881ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:37:58.26175Z","caller":"traceutil/trace.go:171","msg":"trace[761362356] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"353.023731ms","start":"2026-06-23T18:37:57.908687Z","end":"2026-06-23T18:37:58.26171Z","steps":["trace[761362356] 'process raft request' (duration: 352.814832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:37:58.262376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.394755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4419"} {"level":"info","ts":"2026-06-23T18:37:58.262625Z","caller":"traceutil/trace.go:171","msg":"trace[1713215168] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:391; }","duration":"304.622316ms","start":"2026-06-23T18:37:57.957943Z","end":"2026-06-23T18:37:58.262565Z","steps":["trace[1713215168] 'agreement among raft nodes before linearized reading' (duration: 304.147374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:37:58.262677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:37:57.957928Z","time spent":"304.736221ms","remote":"127.0.0.1:40220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4443,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-23T18:37:58.263397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:37:57.908675Z","time spent":"353.67057ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:37:58.466241Z","caller":"traceutil/trace.go:171","msg":"trace[1642984298] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"199.549405ms","start":"2026-06-23T18:37:58.266652Z","end":"2026-06-23T18:37:58.466201Z","steps":["trace[1642984298] 'process raft request' (duration: 184.42712ms)","trace[1642984298] 'compare' (duration: 14.931917ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:37:58.46714Z","caller":"traceutil/trace.go:171","msg":"trace[753076676] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"200.10484ms","start":"2026-06-23T18:37:58.267007Z","end":"2026-06-23T18:37:58.467112Z","steps":["trace[753076676] 'process raft request' (duration: 199.912451ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:37:58.467343Z","caller":"traceutil/trace.go:171","msg":"trace[1981681912] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"199.470992ms","start":"2026-06-23T18:37:58.267829Z","end":"2026-06-23T18:37:58.4673Z","steps":["trace[1981681912] 'process raft request' (duration: 199.242832ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:14.087047Z","caller":"traceutil/trace.go:171","msg":"trace[473528814] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"280.728576ms","start":"2026-06-23T18:38:13.806299Z","end":"2026-06-23T18:38:14.087028Z","steps":["trace[473528814] 'read index received' (duration: 279.631216ms)","trace[473528814] 'applied index is now lower than readState.Index' (duration: 1.09668ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:14.087136Z","caller":"traceutil/trace.go:171","msg":"trace[845943542] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"365.60998ms","start":"2026-06-23T18:38:13.721494Z","end":"2026-06-23T18:38:14.087104Z","steps":["trace[845943542] 'process raft request' (duration: 364.645015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:14.087258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:13.721467Z","time spent":"365.735365ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:38:14.087259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.938723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:14.087444Z","caller":"traceutil/trace.go:171","msg":"trace[438243140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"281.14138ms","start":"2026-06-23T18:38:13.806292Z","end":"2026-06-23T18:38:14.087434Z","steps":["trace[438243140] 'agreement among raft nodes before linearized reading' (duration: 280.877481ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:14.407532Z","caller":"traceutil/trace.go:171","msg":"trace[737046712] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"226.524987ms","start":"2026-06-23T18:38:14.180976Z","end":"2026-06-23T18:38:14.407501Z","steps":["trace[737046712] 'process raft request' (duration: 183.571432ms)","trace[737046712] 'compare' (duration: 42.772649ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:14.41209Z","caller":"traceutil/trace.go:171","msg":"trace[2067343337] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"131.19696ms","start":"2026-06-23T18:38:14.280853Z","end":"2026-06-23T18:38:14.41205Z","steps":["trace[2067343337] 'process raft request' (duration: 127.890468ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:14.754641Z","caller":"traceutil/trace.go:171","msg":"trace[693030245] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"128.354116ms","start":"2026-06-23T18:38:14.626259Z","end":"2026-06-23T18:38:14.754613Z","steps":["trace[693030245] 'process raft request' (duration: 121.092267ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:14.755149Z","caller":"traceutil/trace.go:171","msg":"trace[1661536839] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"125.428368ms","start":"2026-06-23T18:38:14.629712Z","end":"2026-06-23T18:38:14.75514Z","steps":["trace[1661536839] 'process raft request' (duration: 124.847467ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:14.755727Z","caller":"traceutil/trace.go:171","msg":"trace[796802468] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"127.834756ms","start":"2026-06-23T18:38:14.627881Z","end":"2026-06-23T18:38:14.755716Z","steps":["trace[796802468] 'read index received' (duration: 67.648676ms)","trace[796802468] 'applied index is now lower than readState.Index' (duration: 60.18513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:38:14.756056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.145848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:14.756151Z","caller":"traceutil/trace.go:171","msg":"trace[1347559970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:566; }","duration":"128.268212ms","start":"2026-06-23T18:38:14.627874Z","end":"2026-06-23T18:38:14.756142Z","steps":["trace[1347559970] 'agreement among raft nodes before linearized reading' (duration: 128.123877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:14.778672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.737291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:14.778725Z","caller":"traceutil/trace.go:171","msg":"trace[1562283425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:567; }","duration":"150.817224ms","start":"2026-06-23T18:38:14.627898Z","end":"2026-06-23T18:38:14.778715Z","steps":["trace[1562283425] 'agreement among raft nodes before linearized reading' (duration: 150.716101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:14.779368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.402256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:14.779455Z","caller":"traceutil/trace.go:171","msg":"trace[1270082300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:567; }","duration":"151.51594ms","start":"2026-06-23T18:38:14.62792Z","end":"2026-06-23T18:38:14.779436Z","steps":["trace[1270082300] 'agreement among raft nodes before linearized reading' (duration: 151.359844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:14.779781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.462767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-06-23T18:38:14.77987Z","caller":"traceutil/trace.go:171","msg":"trace[469743826] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"119.589522ms","start":"2026-06-23T18:38:14.66026Z","end":"2026-06-23T18:38:14.779849Z","steps":["trace[469743826] 'agreement among raft nodes before linearized reading' (duration: 119.352833ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:15.756059Z","caller":"traceutil/trace.go:171","msg":"trace[699831437] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"286.724956ms","start":"2026-06-23T18:38:15.469284Z","end":"2026-06-23T18:38:15.756009Z","steps":["trace[699831437] 'process raft request' (duration: 262.708819ms)","trace[699831437] 'compare' (duration: 23.740197ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:18.551167Z","caller":"traceutil/trace.go:171","msg":"trace[1234733727] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"105.472502ms","start":"2026-06-23T18:38:18.445674Z","end":"2026-06-23T18:38:18.551147Z","steps":["trace[1234733727] 'process raft request' (duration: 105.284075ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:21.230148Z","caller":"traceutil/trace.go:171","msg":"trace[1231536577] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"419.427953ms","start":"2026-06-23T18:38:20.810692Z","end":"2026-06-23T18:38:21.23012Z","steps":["trace[1231536577] 'read index received' (duration: 418.967637ms)","trace[1231536577] 'applied index is now lower than readState.Index' (duration: 459.446µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:38:21.230323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.620829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:21.230362Z","caller":"traceutil/trace.go:171","msg":"trace[1313910736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"419.702232ms","start":"2026-06-23T18:38:20.810649Z","end":"2026-06-23T18:38:21.230352Z","steps":["trace[1313910736] 'agreement among raft nodes before linearized reading' (duration: 419.607738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:21.2304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:20.81063Z","time spent":"419.763644ms","remote":"127.0.0.1:40036","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-06-23T18:38:21.230635Z","caller":"traceutil/trace.go:171","msg":"trace[1178098404] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"537.97858ms","start":"2026-06-23T18:38:20.692625Z","end":"2026-06-23T18:38:21.230604Z","steps":["trace[1178098404] 'process raft request' (duration: 537.013037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:21.230742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.412559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:38:21.230816Z","caller":"traceutil/trace.go:171","msg":"trace[1204133214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"382.523884ms","start":"2026-06-23T18:38:20.848272Z","end":"2026-06-23T18:38:21.230796Z","steps":["trace[1204133214] 'agreement among raft nodes before linearized reading' (duration: 382.336737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:21.230861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:20.848253Z","time spent":"382.599375ms","remote":"127.0.0.1:40322","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-06-23T18:38:21.230692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.814259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-23T18:38:21.230998Z","caller":"traceutil/trace.go:171","msg":"trace[1982498405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"382.12635ms","start":"2026-06-23T18:38:20.848828Z","end":"2026-06-23T18:38:21.230955Z","steps":["trace[1982498405] 'agreement among raft nodes before linearized reading' (duration: 381.754627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:21.231029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:20.692599Z","time spent":"538.193477ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:38:21.231097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:20.84882Z","time spent":"382.241604ms","remote":"127.0.0.1:40322","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-06-23T18:38:21.231513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.992823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:38:21.231786Z","caller":"traceutil/trace.go:171","msg":"trace[1980548622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"400.04229ms","start":"2026-06-23T18:38:20.831725Z","end":"2026-06-23T18:38:21.231767Z","steps":["trace[1980548622] 'agreement among raft nodes before linearized reading' (duration: 398.608121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:21.231882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:20.831702Z","time spent":"400.161995ms","remote":"127.0.0.1:40322","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-06-23T18:38:21.948564Z","caller":"traceutil/trace.go:171","msg":"trace[1933076144] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"189.860391ms","start":"2026-06-23T18:38:21.758675Z","end":"2026-06-23T18:38:21.948535Z","steps":["trace[1933076144] 'process raft request' (duration: 189.130945ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:22.461127Z","caller":"traceutil/trace.go:171","msg":"trace[1948286158] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"402.178943ms","start":"2026-06-23T18:38:22.058921Z","end":"2026-06-23T18:38:22.4611Z","steps":["trace[1948286158] 'process raft request' (duration: 401.955444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:22.461269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:22.058902Z","time spent":"402.296616ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:38:23.063417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627415166273607,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:38:23.670429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.40784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-23T18:38:23.671397Z","caller":"traceutil/trace.go:171","msg":"trace[1676577615] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"1.108006174s","start":"2026-06-23T18:38:22.563377Z","end":"2026-06-23T18:38:23.671383Z","steps":["trace[1676577615] 'read index received' (duration: 646.482396ms)","trace[1676577615] 'applied index is now lower than readState.Index' (duration: 461.522988ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:23.671471Z","caller":"traceutil/trace.go:171","msg":"trace[858766667] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.241494185s","start":"2026-06-23T18:38:22.42997Z","end":"2026-06-23T18:38:23.671464Z","steps":["trace[858766667] 'process raft request' (duration: 1.240691408s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.671552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:22.429943Z","time spent":"1.241544957s","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:38:23.67156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.14838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:23.671642Z","caller":"traceutil/trace.go:171","msg":"trace[134629132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"979.264433ms","start":"2026-06-23T18:38:22.692353Z","end":"2026-06-23T18:38:23.671618Z","steps":["trace[134629132] 'agreement among raft nodes before linearized reading' (duration: 979.143119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.671693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:22.692335Z","time spent":"979.346376ms","remote":"127.0.0.1:40036","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-06-23T18:38:23.671765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.678311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-23T18:38:23.671837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.359091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-23T18:38:23.671872Z","caller":"traceutil/trace.go:171","msg":"trace[6718489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"355.412653ms","start":"2026-06-23T18:38:23.316451Z","end":"2026-06-23T18:38:23.671864Z","steps":["trace[6718489] 'agreement among raft nodes before linearized reading' (duration: 355.34755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.671881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.35331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:38:23.67189Z","caller":"traceutil/trace.go:171","msg":"trace[506837413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"355.853038ms","start":"2026-06-23T18:38:23.316007Z","end":"2026-06-23T18:38:23.67186Z","steps":["trace[506837413] 'agreement among raft nodes before linearized reading' (duration: 355.534637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.671938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.041543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:38:23.671939Z","caller":"traceutil/trace.go:171","msg":"trace[1014593748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"866.430913ms","start":"2026-06-23T18:38:22.805484Z","end":"2026-06-23T18:38:23.671915Z","steps":["trace[1014593748] 'agreement among raft nodes before linearized reading' (duration: 866.372161ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:23.671962Z","caller":"traceutil/trace.go:171","msg":"trace[858030682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"356.131357ms","start":"2026-06-23T18:38:23.315825Z","end":"2026-06-23T18:38:23.671956Z","steps":["trace[858030682] 'agreement among raft nodes before linearized reading' (duration: 356.091025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.67198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:23.315785Z","time spent":"356.191288ms","remote":"127.0.0.1:40322","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-06-23T18:38:23.671981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:22.805464Z","time spent":"866.509295ms","remote":"127.0.0.1:40050","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-06-23T18:38:23.671985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:23.315995Z","time spent":"355.978441ms","remote":"127.0.0.1:40322","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-06-23T18:38:23.671894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:23.316443Z","time spent":"355.444813ms","remote":"127.0.0.1:40322","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-06-23T18:38:23.671783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108389226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T18:38:23.672176Z","caller":"traceutil/trace.go:171","msg":"trace[756780797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.108848512s","start":"2026-06-23T18:38:22.563319Z","end":"2026-06-23T18:38:23.672167Z","steps":["trace[756780797] 'agreement among raft nodes before linearized reading' (duration: 1.108171509s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:23.672214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:22.563278Z","time spent":"1.108927674s","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-23T18:38:24.149374Z","caller":"traceutil/trace.go:171","msg":"trace[1788837482] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"101.826168ms","start":"2026-06-23T18:38:24.047524Z","end":"2026-06-23T18:38:24.14935Z","steps":["trace[1788837482] 'process raft request' (duration: 101.637521ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:24.240063Z","caller":"traceutil/trace.go:171","msg":"trace[820993119] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"179.345176ms","start":"2026-06-23T18:38:24.060685Z","end":"2026-06-23T18:38:24.240031Z","steps":["trace[820993119] 'process raft request' (duration: 176.107107ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:24.545923Z","caller":"traceutil/trace.go:171","msg":"trace[1303143985] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"105.879342ms","start":"2026-06-23T18:38:24.440015Z","end":"2026-06-23T18:38:24.545895Z","steps":["trace[1303143985] 'process raft request' (duration: 39.844845ms)","trace[1303143985] 'compare' (duration: 65.872192ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:35.868426Z","caller":"traceutil/trace.go:171","msg":"trace[183226432] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"120.913973ms","start":"2026-06-23T18:38:35.747491Z","end":"2026-06-23T18:38:35.868405Z","steps":["trace[183226432] 'read index received' (duration: 120.694927ms)","trace[183226432] 'applied index is now lower than readState.Index' (duration: 218.056µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:35.868508Z","caller":"traceutil/trace.go:171","msg":"trace[1140281532] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"123.512921ms","start":"2026-06-23T18:38:35.744961Z","end":"2026-06-23T18:38:35.868474Z","steps":["trace[1140281532] 'process raft request' (duration: 123.306285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:35.868797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.197522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-23T18:38:35.868846Z","caller":"traceutil/trace.go:171","msg":"trace[310624307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"121.278425ms","start":"2026-06-23T18:38:35.747556Z","end":"2026-06-23T18:38:35.868834Z","steps":["trace[310624307] 'agreement among raft nodes before linearized reading' (duration: 120.972846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:35.868824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.311126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:38:35.869257Z","caller":"traceutil/trace.go:171","msg":"trace[1440248522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"121.781259ms","start":"2026-06-23T18:38:35.747444Z","end":"2026-06-23T18:38:35.869225Z","steps":["trace[1440248522] 'agreement among raft nodes before linearized reading' (duration: 121.151201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:35.869133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.519312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T18:38:35.869558Z","caller":"traceutil/trace.go:171","msg":"trace[296183300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"121.898633ms","start":"2026-06-23T18:38:35.747563Z","end":"2026-06-23T18:38:35.869462Z","steps":["trace[296183300] 'agreement among raft nodes before linearized reading' (duration: 121.372248ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:38:39.344221Z","caller":"traceutil/trace.go:171","msg":"trace[875107447] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"110.604403ms","start":"2026-06-23T18:38:39.233575Z","end":"2026-06-23T18:38:39.344179Z","steps":["trace[875107447] 'process raft request' (duration: 110.438418ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:26.434449Z","caller":"traceutil/trace.go:171","msg":"trace[166068953] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"180.425911ms","start":"2026-06-23T18:39:26.253986Z","end":"2026-06-23T18:39:26.434412Z","steps":["trace[166068953] 'process raft request' (duration: 179.158895ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:26.437885Z","caller":"traceutil/trace.go:171","msg":"trace[1414699556] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"180.632084ms","start":"2026-06-23T18:39:26.257232Z","end":"2026-06-23T18:39:26.437864Z","steps":["trace[1414699556] 'process raft request' (duration: 180.45467ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:35.626852Z","caller":"traceutil/trace.go:171","msg":"trace[1135493502] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"184.297303ms","start":"2026-06-23T18:39:35.442521Z","end":"2026-06-23T18:39:35.626819Z","steps":["trace[1135493502] 'process raft request' (duration: 184.12894ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:37.520658Z","caller":"traceutil/trace.go:171","msg":"trace[785779953] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"145.18365ms","start":"2026-06-23T18:39:37.375457Z","end":"2026-06-23T18:39:37.52064Z","steps":["trace[785779953] 'process raft request' (duration: 144.678341ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:38.043777Z","caller":"traceutil/trace.go:171","msg":"trace[1418769117] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"106.892938ms","start":"2026-06-23T18:39:37.936863Z","end":"2026-06-23T18:39:38.043756Z","steps":["trace[1418769117] 'process raft request' (duration: 61.36377ms)","trace[1418769117] 'compare' (duration: 45.373275ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:38.043906Z","caller":"traceutil/trace.go:171","msg":"trace[499627261] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"105.40416ms","start":"2026-06-23T18:39:37.938471Z","end":"2026-06-23T18:39:38.043875Z","steps":["trace[499627261] 'read index received' (duration: 59.700959ms)","trace[499627261] 'applied index is now lower than readState.Index' (duration: 45.700821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:38.044053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.559423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:39:38.0441Z","caller":"traceutil/trace.go:171","msg":"trace[725815015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com; range_end:; response_count:0; response_revision:1496; }","duration":"105.660475ms","start":"2026-06-23T18:39:37.938421Z","end":"2026-06-23T18:39:38.044081Z","steps":["trace[725815015] 'agreement among raft nodes before linearized reading' (duration: 105.553773ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:02.401516Z","caller":"traceutil/trace.go:171","msg":"trace[129678224] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"115.661082ms","start":"2026-06-23T18:40:02.285833Z","end":"2026-06-23T18:40:02.401494Z","steps":["trace[129678224] 'read index received' (duration: 115.422159ms)","trace[129678224] 'applied index is now lower than readState.Index' (duration: 238.043µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:02.401578Z","caller":"traceutil/trace.go:171","msg":"trace[652111731] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"140.651466ms","start":"2026-06-23T18:40:02.260902Z","end":"2026-06-23T18:40:02.401553Z","steps":["trace[652111731] 'process raft request' (duration: 140.429303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:02.401875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.997079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:02.401948Z","caller":"traceutil/trace.go:171","msg":"trace[1980811448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"116.10699ms","start":"2026-06-23T18:40:02.285821Z","end":"2026-06-23T18:40:02.401928Z","steps":["trace[1980811448] 'agreement among raft nodes before linearized reading' (duration: 115.772135ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:03.019208Z","caller":"traceutil/trace.go:171","msg":"trace[419344914] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"125.032218ms","start":"2026-06-23T18:40:02.894146Z","end":"2026-06-23T18:40:03.019179Z","steps":["trace[419344914] 'process raft request' (duration: 93.028729ms)","trace[419344914] 'compare' (duration: 31.891986ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:03.298158Z","caller":"traceutil/trace.go:171","msg":"trace[832731453] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"197.88875ms","start":"2026-06-23T18:40:03.100235Z","end":"2026-06-23T18:40:03.298124Z","steps":["trace[832731453] 'process raft request' (duration: 156.290085ms)","trace[832731453] 'compare' (duration: 41.349061ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:03.298609Z","caller":"traceutil/trace.go:171","msg":"trace[533863052] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"180.504422ms","start":"2026-06-23T18:40:03.118072Z","end":"2026-06-23T18:40:03.298576Z","steps":["trace[533863052] 'read index received' (duration: 138.430139ms)","trace[533863052] 'applied index is now lower than readState.Index' (duration: 42.072423ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:03.298758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.674006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:03.298804Z","caller":"traceutil/trace.go:171","msg":"trace[1264986373] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1869; }","duration":"180.768347ms","start":"2026-06-23T18:40:03.118021Z","end":"2026-06-23T18:40:03.298789Z","steps":["trace[1264986373] 'agreement among raft nodes before linearized reading' (duration: 180.676975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:04.305039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627415166276108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:40:05.03071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310511932s","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-06-23T18:40:05.030798Z","caller":"traceutil/trace.go:171","msg":"trace[1733707302] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1869; }","duration":"1.310613263s","start":"2026-06-23T18:40:03.720162Z","end":"2026-06-23T18:40:05.030776Z","steps":["trace[1733707302] 'range keys from in-memory index tree' (duration: 1.310338309s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.03092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.720142Z","time spent":"1.310764175s","remote":"127.0.0.1:40322","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-06-23T18:40:05.030928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487841242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-23T18:40:05.031008Z","caller":"traceutil/trace.go:171","msg":"trace[1062929539] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec; range_end:; response_count:1; response_revision:1869; }","duration":"1.487956564s","start":"2026-06-23T18:40:03.543021Z","end":"2026-06-23T18:40:05.030977Z","steps":["trace[1062929539] 'range keys from in-memory index tree' (duration: 1.487658019s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.031047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.543008Z","time spent":"1.488029246s","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" "} {"level":"warn","ts":"2026-06-23T18:40:05.031488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621620086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:05.031568Z","caller":"traceutil/trace.go:171","msg":"trace[730157639] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"1.621742468s","start":"2026-06-23T18:40:03.409805Z","end":"2026-06-23T18:40:05.031547Z","steps":["trace[730157639] 'range keys from in-memory index tree' (duration: 1.621493764s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.031752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.409789Z","time spent":"1.62185375s","remote":"127.0.0.1:34446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-23T18:40:05.031932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608004902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-23T18:40:05.031972Z","caller":"traceutil/trace.go:171","msg":"trace[1616506583] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec; range_end:; response_count:1; response_revision:1869; }","duration":"1.608111993s","start":"2026-06-23T18:40:03.42385Z","end":"2026-06-23T18:40:05.031962Z","steps":["trace[1616506583] 'range keys from in-memory index tree' (duration: 1.607880639s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.03205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.423839Z","time spent":"1.608202615s","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" "} {"level":"warn","ts":"2026-06-23T18:40:05.033552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.168009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:40:05.033634Z","caller":"traceutil/trace.go:171","msg":"trace[1518701439] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"1.302327966s","start":"2026-06-23T18:40:03.731266Z","end":"2026-06-23T18:40:05.033594Z","steps":["trace[1518701439] 'process raft request' (duration: 990.068066ms)","trace[1518701439] 'compare' (duration: 312.039237ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:05.034209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.731247Z","time spent":"1.302413658s","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:05.805792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000773763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-23T18:40:05.805941Z","caller":"traceutil/trace.go:171","msg":"trace[1023358681] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000980977s","start":"2026-06-23T18:40:03.804935Z","end":"2026-06-23T18:40:05.805916Z","steps":["trace[1023358681] 'agreement among raft nodes before linearized reading' (duration: 2.000808264s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.806005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:03.80492Z","time spent":"2.001071108s","remote":"127.0.0.1:40036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-23T18:40:05.841164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.733845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:40:05.84126Z","caller":"traceutil/trace.go:171","msg":"trace[1281191495] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1948; }","duration":"2.036259663s","start":"2026-06-23T18:40:03.804984Z","end":"2026-06-23T18:40:05.841244Z","steps":["trace[1281191495] 'read index received' (duration: 916.912911ms)","trace[1281191495] 'applied index is now lower than readState.Index' (duration: 1.119345492s)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:05.841452Z","caller":"traceutil/trace.go:171","msg":"trace[2012799868] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"1.808616807s","start":"2026-06-23T18:40:04.032822Z","end":"2026-06-23T18:40:05.841439Z","steps":["trace[2012799868] 'process raft request' (duration: 1.644524656s)","trace[2012799868] 'compare' (duration: 163.270397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:05.841517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.0328Z","time spent":"1.808686598s","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:05.92975Z","caller":"traceutil/trace.go:171","msg":"trace[1335934581] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"902.641083ms","start":"2026-06-23T18:40:05.027078Z","end":"2026-06-23T18:40:05.929719Z","steps":["trace[1335934581] 'process raft request' (duration: 901.604316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.92991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423769479s","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-06-23T18:40:05.929936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.02705Z","time spent":"902.787106ms","remote":"127.0.0.1:40322","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-06-23T18:40:05.929973Z","caller":"traceutil/trace.go:171","msg":"trace[733159030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1874; }","duration":"1.423871411s","start":"2026-06-23T18:40:04.506086Z","end":"2026-06-23T18:40:05.929957Z","steps":["trace[733159030] 'agreement among raft nodes before linearized reading' (duration: 1.423667367s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.506066Z","time spent":"1.423939231s","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T18:40:05.930064Z","caller":"traceutil/trace.go:171","msg":"trace[1210762526] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"890.704188ms","start":"2026-06-23T18:40:05.039327Z","end":"2026-06-23T18:40:05.930032Z","steps":["trace[1210762526] 'process raft request' (duration: 890.28679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093711275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:40:05.930185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.039319Z","time spent":"890.802339ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:05.9302Z","caller":"traceutil/trace.go:171","msg":"trace[1569086411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1874; }","duration":"1.093827547s","start":"2026-06-23T18:40:04.83636Z","end":"2026-06-23T18:40:05.930188Z","steps":["trace[1569086411] 'agreement among raft nodes before linearized reading' (duration: 1.093731356s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.836348Z","time spent":"1.093887648s","remote":"127.0.0.1:54280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:40:05.930304Z","caller":"traceutil/trace.go:171","msg":"trace[898724994] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"890.581916ms","start":"2026-06-23T18:40:05.039502Z","end":"2026-06-23T18:40:05.930084Z","steps":["trace[898724994] 'process raft request' (duration: 890.173398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357539862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:40:05.930475Z","caller":"traceutil/trace.go:171","msg":"trace[455511789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"1.357565843s","start":"2026-06-23T18:40:04.572902Z","end":"2026-06-23T18:40:05.930468Z","steps":["trace[455511789] 'agreement among raft nodes before linearized reading' (duration: 1.357471801s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.572853Z","time spent":"1.357637914s","remote":"127.0.0.1:40322","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-06-23T18:40:05.930438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.039458Z","time spent":"890.92768ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:05.930603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129272328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-23T18:40:05.930626Z","caller":"traceutil/trace.go:171","msg":"trace[1172850928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"1.129316929s","start":"2026-06-23T18:40:04.801304Z","end":"2026-06-23T18:40:05.93062Z","steps":["trace[1172850928] 'agreement among raft nodes before linearized reading' (duration: 1.129258158s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.801291Z","time spent":"1.129348339s","remote":"127.0.0.1:40322","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-06-23T18:40:05.930351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.114298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:40:05.930715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.392358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-23T18:40:05.930757Z","caller":"traceutil/trace.go:171","msg":"trace[1831338397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"124.553095ms","start":"2026-06-23T18:40:05.806183Z","end":"2026-06-23T18:40:05.930736Z","steps":["trace[1831338397] 'agreement among raft nodes before linearized reading' (duration: 124.140838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135465199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-23T18:40:05.930813Z","caller":"traceutil/trace.go:171","msg":"trace[144927585] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1874; }","duration":"891.525151ms","start":"2026-06-23T18:40:05.039265Z","end":"2026-06-23T18:40:05.93079Z","steps":["trace[144927585] 'agreement among raft nodes before linearized reading' (duration: 890.638947ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:05.930854Z","caller":"traceutil/trace.go:171","msg":"trace[545505833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"1.135549381s","start":"2026-06-23T18:40:04.795291Z","end":"2026-06-23T18:40:05.93084Z","steps":["trace[545505833] 'agreement among raft nodes before linearized reading' (duration: 1.135433029s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.930865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.039253Z","time spent":"891.602372ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-23T18:40:05.930893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.795266Z","time spent":"1.135618511s","remote":"127.0.0.1:40322","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-06-23T18:40:05.930526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391725283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T18:40:05.930961Z","caller":"traceutil/trace.go:171","msg":"trace[609821141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"1.39218024s","start":"2026-06-23T18:40:04.538767Z","end":"2026-06-23T18:40:05.930947Z","steps":["trace[609821141] 'agreement among raft nodes before linearized reading' (duration: 1.391686753s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:05.931059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:04.538753Z","time spent":"1.392289242s","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-23T18:40:06.440929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627415166276115,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:40:06.538273Z","caller":"traceutil/trace.go:171","msg":"trace[1736692784] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1953; }","duration":"597.788488ms","start":"2026-06-23T18:40:05.940464Z","end":"2026-06-23T18:40:06.538253Z","steps":["trace[1736692784] 'read index received' (duration: 578.693387ms)","trace[1736692784] 'applied index is now lower than readState.Index' (duration: 19.094221ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:06.538509Z","caller":"traceutil/trace.go:171","msg":"trace[2023439953] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"603.018502ms","start":"2026-06-23T18:40:05.935367Z","end":"2026-06-23T18:40:06.538386Z","steps":["trace[2023439953] 'process raft request' (duration: 583.82248ms)","trace[2023439953] 'compare' (duration: 18.701794ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:06.538623Z","caller":"traceutil/trace.go:171","msg":"trace[1725295164] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"601.205913ms","start":"2026-06-23T18:40:05.937369Z","end":"2026-06-23T18:40:06.538575Z","steps":["trace[1725295164] 'process raft request' (duration: 600.803057ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:06.538696Z","caller":"traceutil/trace.go:171","msg":"trace[1743585516] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"602.975412ms","start":"2026-06-23T18:40:05.935701Z","end":"2026-06-23T18:40:06.538677Z","steps":["trace[1743585516] 'process raft request' (duration: 602.344122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.538791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.937348Z","time spent":"601.349005ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:06.538853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.113195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-06-23T18:40:06.538899Z","caller":"traceutil/trace.go:171","msg":"trace[1775956155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec; range_end:; response_count:1; response_revision:1881; }","duration":"500.194506ms","start":"2026-06-23T18:40:06.038693Z","end":"2026-06-23T18:40:06.538888Z","steps":["trace[1775956155] 'agreement among raft nodes before linearized reading' (duration: 499.993243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.538899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.935678Z","time spent":"603.093583ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:06.539088Z","caller":"traceutil/trace.go:171","msg":"trace[370508218] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"602.995172ms","start":"2026-06-23T18:40:05.936081Z","end":"2026-06-23T18:40:06.539076Z","steps":["trace[370508218] 'process raft request' (duration: 602.055117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.539118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.870981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-06-23T18:40:06.539235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.936073Z","time spent":"603.075683ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:06.539255Z","caller":"traceutil/trace.go:171","msg":"trace[264364077] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"603.345248ms","start":"2026-06-23T18:40:05.935896Z","end":"2026-06-23T18:40:06.539241Z","steps":["trace[264364077] 'process raft request' (duration: 602.20841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.539367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.935874Z","time spent":"603.419869ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:06.5394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.533196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:06.539546Z","caller":"traceutil/trace.go:171","msg":"trace[7207719] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"496.691578ms","start":"2026-06-23T18:40:06.042835Z","end":"2026-06-23T18:40:06.539527Z","steps":["trace[7207719] 'agreement among raft nodes before linearized reading' (duration: 496.432744ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:06.53924Z","caller":"traceutil/trace.go:171","msg":"trace[79781278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1881; }","duration":"409.018222ms","start":"2026-06-23T18:40:06.13021Z","end":"2026-06-23T18:40:06.539228Z","steps":["trace[79781278] 'agreement among raft nodes before linearized reading' (duration: 408.808789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.538607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.935332Z","time spent":"603.219067ms","remote":"127.0.0.1:40112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:40:06.538982Z","caller":"traceutil/trace.go:171","msg":"trace[128041013] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"601.466027ms","start":"2026-06-23T18:40:05.937487Z","end":"2026-06-23T18:40:06.538953Z","steps":["trace[128041013] 'process raft request' (duration: 600.711555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.540078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.937478Z","time spent":"602.506913ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:06.539835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:06.130192Z","time spent":"409.598702ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10352,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-23T18:40:06.538991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:06.038657Z","time spent":"500.323139ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5dad8ecd-f333-4f6c-98fe-a4656a7fbeec\" "} {"level":"warn","ts":"2026-06-23T18:40:06.538865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.330247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:06.540534Z","caller":"traceutil/trace.go:171","msg":"trace[1995735643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"600.069955ms","start":"2026-06-23T18:40:05.940445Z","end":"2026-06-23T18:40:06.540515Z","steps":["trace[1995735643] 'agreement among raft nodes before linearized reading' (duration: 598.191814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.540618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.940438Z","time spent":"600.163006ms","remote":"127.0.0.1:40036","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-06-23T18:40:06.539033Z","caller":"traceutil/trace.go:171","msg":"trace[1399413495] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"601.213393ms","start":"2026-06-23T18:40:05.937809Z","end":"2026-06-23T18:40:06.539023Z","steps":["trace[1399413495] 'process raft request' (duration: 600.414751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:06.540862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:05.937802Z","time spent":"602.937371ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:06.539619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:06.042829Z","time spent":"496.772159ms","remote":"127.0.0.1:34446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-23T18:40:06.745692Z","caller":"traceutil/trace.go:171","msg":"trace[481470331] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"162.901979ms","start":"2026-06-23T18:40:06.582761Z","end":"2026-06-23T18:40:06.745663Z","steps":["trace[481470331] 'process raft request' (duration: 153.795911ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:07.741512Z","caller":"traceutil/trace.go:171","msg":"trace[2075240417] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"116.351794ms","start":"2026-06-23T18:40:07.625116Z","end":"2026-06-23T18:40:07.741468Z","steps":["trace[2075240417] 'process raft request' (duration: 116.179871ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:18.746691Z","caller":"traceutil/trace.go:171","msg":"trace[1050363166] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"130.083111ms","start":"2026-06-23T18:40:18.616576Z","end":"2026-06-23T18:40:18.746659Z","steps":["trace[1050363166] 'process raft request' (duration: 112.853303ms)","trace[1050363166] 'compare' (duration: 17.103766ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:18.789327Z","caller":"traceutil/trace.go:171","msg":"trace[2098018998] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"171.907729ms","start":"2026-06-23T18:40:18.617395Z","end":"2026-06-23T18:40:18.789303Z","steps":["trace[2098018998] 'process raft request' (duration: 171.708216ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:18.789529Z","caller":"traceutil/trace.go:171","msg":"trace[2006488051] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2070; }","duration":"150.763942ms","start":"2026-06-23T18:40:18.638743Z","end":"2026-06-23T18:40:18.789507Z","steps":["trace[2006488051] 'read index received' (duration: 90.677359ms)","trace[2006488051] 'applied index is now lower than readState.Index' (duration: 60.085303ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:18.789746Z","caller":"traceutil/trace.go:171","msg":"trace[644858627] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"171.510273ms","start":"2026-06-23T18:40:18.618196Z","end":"2026-06-23T18:40:18.789706Z","steps":["trace[644858627] 'process raft request' (duration: 171.068386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:18.789783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.026346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:18.78984Z","caller":"traceutil/trace.go:171","msg":"trace[266857928] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1991; }","duration":"151.131488ms","start":"2026-06-23T18:40:18.638696Z","end":"2026-06-23T18:40:18.789827Z","steps":["trace[266857928] 'agreement among raft nodes before linearized reading' (duration: 150.926465ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:19.253633Z","caller":"traceutil/trace.go:171","msg":"trace[1649627747] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"106.933315ms","start":"2026-06-23T18:40:19.146673Z","end":"2026-06-23T18:40:19.253606Z","steps":["trace[1649627747] 'process raft request' (duration: 106.723031ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:20.05605Z","caller":"traceutil/trace.go:171","msg":"trace[2065684323] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"156.54322ms","start":"2026-06-23T18:40:19.899485Z","end":"2026-06-23T18:40:20.056028Z","steps":["trace[2065684323] 'process raft request' (duration: 156.369197ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:22.889202Z","caller":"traceutil/trace.go:171","msg":"trace[401832810] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"282.003209ms","start":"2026-06-23T18:40:22.60718Z","end":"2026-06-23T18:40:22.889183Z","steps":["trace[401832810] 'process raft request' (duration: 281.880866ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:22.918223Z","caller":"traceutil/trace.go:171","msg":"trace[479624890] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2092; }","duration":"228.885785ms","start":"2026-06-23T18:40:22.689312Z","end":"2026-06-23T18:40:22.918198Z","steps":["trace[479624890] 'read index received' (duration: 199.782469ms)","trace[479624890] 'applied index is now lower than readState.Index' (duration: 29.102216ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:22.918298Z","caller":"traceutil/trace.go:171","msg":"trace[1230866372] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"308.095288ms","start":"2026-06-23T18:40:22.610173Z","end":"2026-06-23T18:40:22.918268Z","steps":["trace[1230866372] 'process raft request' (duration: 307.892395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.918364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.035678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:22.918398Z","caller":"traceutil/trace.go:171","msg":"trace[580959210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"229.120439ms","start":"2026-06-23T18:40:22.689268Z","end":"2026-06-23T18:40:22.918388Z","steps":["trace[580959210] 'agreement among raft nodes before linearized reading' (duration: 229.046708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.918412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.506677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:40:22.918446Z","caller":"traceutil/trace.go:171","msg":"trace[312432460] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2011; }","duration":"220.573669ms","start":"2026-06-23T18:40:22.697864Z","end":"2026-06-23T18:40:22.918438Z","steps":["trace[312432460] 'agreement among raft nodes before linearized reading' (duration: 220.503898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.918461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.048436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:22.918487Z","caller":"traceutil/trace.go:171","msg":"trace[1201909054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"112.100546ms","start":"2026-06-23T18:40:22.806378Z","end":"2026-06-23T18:40:22.918478Z","steps":["trace[1201909054] 'agreement among raft nodes before linearized reading' (duration: 112.052356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.918566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.335412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:22.91861Z","caller":"traceutil/trace.go:171","msg":"trace[386788312] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"103.390754ms","start":"2026-06-23T18:40:22.815204Z","end":"2026-06-23T18:40:22.918595Z","steps":["trace[386788312] 'agreement among raft nodes before linearized reading' (duration: 103.264212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.91864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.181798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-23T18:40:22.918665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.552323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-23T18:40:22.918728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:22.610156Z","time spent":"308.19498ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:22.918735Z","caller":"traceutil/trace.go:171","msg":"trace[1195906030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2011; }","duration":"112.32941ms","start":"2026-06-23T18:40:22.806383Z","end":"2026-06-23T18:40:22.918713Z","steps":["trace[1195906030] 'agreement among raft nodes before linearized reading' (duration: 112.039505ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:22.918742Z","caller":"traceutil/trace.go:171","msg":"trace[464301469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2011; }","duration":"157.687255ms","start":"2026-06-23T18:40:22.761037Z","end":"2026-06-23T18:40:22.918725Z","steps":["trace[464301469] 'agreement among raft nodes before linearized reading' (duration: 157.427611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:22.918747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.952604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T18:40:22.918845Z","caller":"traceutil/trace.go:171","msg":"trace[916358328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2011; }","duration":"112.063056ms","start":"2026-06-23T18:40:22.806774Z","end":"2026-06-23T18:40:22.918838Z","steps":["trace[916358328] 'agreement among raft nodes before linearized reading' (duration: 111.913893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:55.914685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.815727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:55.914784Z","caller":"traceutil/trace.go:171","msg":"trace[1594242049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2193; }","duration":"107.997309ms","start":"2026-06-23T18:40:55.806766Z","end":"2026-06-23T18:40:55.914763Z","steps":["trace[1594242049] 'range keys from in-memory index tree' (duration: 107.557343ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:56.457311Z","caller":"traceutil/trace.go:171","msg":"trace[1496089212] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"253.945909ms","start":"2026-06-23T18:40:56.203342Z","end":"2026-06-23T18:40:56.457288Z","steps":["trace[1496089212] 'process raft request' (duration: 253.786596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.787896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.484886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:40:56.788039Z","caller":"traceutil/trace.go:171","msg":"trace[2088838695] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2283; }","duration":"555.17967ms","start":"2026-06-23T18:40:56.232802Z","end":"2026-06-23T18:40:56.787982Z","steps":["trace[2088838695] 'read index received' (duration: 224.351548ms)","trace[2088838695] 'applied index is now lower than readState.Index' (duration: 330.827032ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:56.788106Z","caller":"traceutil/trace.go:171","msg":"trace[522758327] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"571.536488ms","start":"2026-06-23T18:40:56.216514Z","end":"2026-06-23T18:40:56.788051Z","steps":["trace[522758327] 'process raft request' (duration: 456.523185ms)","trace[522758327] 'compare' (duration: 114.345084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:56.788353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.216486Z","time spent":"571.74256ms","remote":"127.0.0.1:40322","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-06-23T18:40:56.788572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.747418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-06-23T18:40:56.78855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.735687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:40:56.788652Z","caller":"traceutil/trace.go:171","msg":"trace[1495356731] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2195; }","duration":"555.857499ms","start":"2026-06-23T18:40:56.232756Z","end":"2026-06-23T18:40:56.788614Z","steps":["trace[1495356731] 'agreement among raft nodes before linearized reading' (duration: 555.424652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.788784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.232742Z","time spent":"556.009121ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-23T18:40:56.788761Z","caller":"traceutil/trace.go:171","msg":"trace[1322979550] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2195; }","duration":"400.876788ms","start":"2026-06-23T18:40:56.387749Z","end":"2026-06-23T18:40:56.788625Z","steps":["trace[1322979550] 'agreement among raft nodes before linearized reading' (duration: 400.651245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.788972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.387736Z","time spent":"401.142192ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:40:57.33671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627415166276875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:40:57.611914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.664583ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:57.612051Z","caller":"traceutil/trace.go:171","msg":"trace[488425273] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2195; }","duration":"701.816695ms","start":"2026-06-23T18:40:56.910215Z","end":"2026-06-23T18:40:57.612031Z","steps":["trace[488425273] 'range keys from in-memory index tree' (duration: 701.647452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.612677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.229467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:40:57.612936Z","caller":"traceutil/trace.go:171","msg":"trace[1012517586] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"819.662318ms","start":"2026-06-23T18:40:56.793256Z","end":"2026-06-23T18:40:57.612919Z","steps":["trace[1012517586] 'process raft request' (duration: 580.013725ms)","trace[1012517586] 'compare' (duration: 239.024934ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:57.61307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.793238Z","time spent":"819.746889ms","remote":"127.0.0.1:40322","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-06-23T18:40:57.612784Z","caller":"traceutil/trace.go:171","msg":"trace[1543999277] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"777.118315ms","start":"2026-06-23T18:40:56.835649Z","end":"2026-06-23T18:40:57.612768Z","steps":["trace[1543999277] 'read index received' (duration: 537.653114ms)","trace[1543999277] 'applied index is now lower than readState.Index' (duration: 239.463771ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:57.613416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.759393ms","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-06-23T18:40:57.613478Z","caller":"traceutil/trace.go:171","msg":"trace[498739] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2196; }","duration":"777.854755ms","start":"2026-06-23T18:40:56.835607Z","end":"2026-06-23T18:40:57.613461Z","steps":["trace[498739] 'agreement among raft nodes before linearized reading' (duration: 777.707753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.613643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.835593Z","time spent":"777.993967ms","remote":"127.0.0.1:54646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:40:57.672533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.418663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-06-23T18:40:57.672625Z","caller":"traceutil/trace.go:171","msg":"trace[633297992] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2197; }","duration":"822.515964ms","start":"2026-06-23T18:40:56.850089Z","end":"2026-06-23T18:40:57.672605Z","steps":["trace[633297992] 'agreement among raft nodes before linearized reading' (duration: 822.294661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.672632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.812042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:40:57.672683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:56.850021Z","time spent":"822.647776ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-23T18:40:57.672712Z","caller":"traceutil/trace.go:171","msg":"trace[110372817] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2197; }","duration":"397.925634ms","start":"2026-06-23T18:40:57.274766Z","end":"2026-06-23T18:40:57.672691Z","steps":["trace[110372817] 'agreement among raft nodes before linearized reading' (duration: 397.797363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.672757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.274748Z","time spent":"397.998425ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:40:57.672743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.14669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-23T18:40:57.672956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.527964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-23T18:40:57.673025Z","caller":"traceutil/trace.go:171","msg":"trace[205426611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2197; }","duration":"583.443815ms","start":"2026-06-23T18:40:57.089515Z","end":"2026-06-23T18:40:57.672959Z","steps":["trace[205426611] 'agreement among raft nodes before linearized reading' (duration: 582.888218ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.673048Z","caller":"traceutil/trace.go:171","msg":"trace[965677098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2197; }","duration":"585.684976ms","start":"2026-06-23T18:40:57.087352Z","end":"2026-06-23T18:40:57.673037Z","steps":["trace[965677098] 'agreement among raft nodes before linearized reading' (duration: 585.528384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.673086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.087083Z","time spent":"585.994861ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:40:57.673219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.712224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:40:57.673253Z","caller":"traceutil/trace.go:171","msg":"trace[998978354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2197; }","duration":"587.772255ms","start":"2026-06-23T18:40:57.085473Z","end":"2026-06-23T18:40:57.673245Z","steps":["trace[998978354] 'agreement among raft nodes before linearized reading' (duration: 587.688654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.673276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.089505Z","time spent":"583.597917ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-23T18:40:57.673346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.373243ms","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-06-23T18:40:57.673406Z","caller":"traceutil/trace.go:171","msg":"trace[728981079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2197; }","duration":"634.453004ms","start":"2026-06-23T18:40:57.038934Z","end":"2026-06-23T18:40:57.673387Z","steps":["trace[728981079] 'agreement among raft nodes before linearized reading' (duration: 634.278542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.673467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.038917Z","time spent":"634.532595ms","remote":"127.0.0.1:40322","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-06-23T18:40:57.67356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.257938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-23T18:40:57.67297Z","caller":"traceutil/trace.go:171","msg":"trace[646313348] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"292.539258ms","start":"2026-06-23T18:40:57.380414Z","end":"2026-06-23T18:40:57.672953Z","steps":["trace[646313348] 'process raft request' (duration: 291.824488ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.673601Z","caller":"traceutil/trace.go:171","msg":"trace[1193934234] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:2197; }","duration":"454.31438ms","start":"2026-06-23T18:40:57.219277Z","end":"2026-06-23T18:40:57.673591Z","steps":["trace[1193934234] 'agreement among raft nodes before linearized reading' (duration: 454.216757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.673752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.219268Z","time spent":"454.472992ms","remote":"127.0.0.1:40074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-06-23T18:40:57.673282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.085459Z","time spent":"587.816495ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T18:40:57.673757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.496951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-23T18:40:57.674112Z","caller":"traceutil/trace.go:171","msg":"trace[1616542714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2197; }","duration":"533.865476ms","start":"2026-06-23T18:40:57.140231Z","end":"2026-06-23T18:40:57.674097Z","steps":["trace[1616542714] 'agreement among raft nodes before linearized reading' (duration: 533.4551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.674165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.140221Z","time spent":"533.928206ms","remote":"127.0.0.1:34446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-23T18:40:57.673637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.5762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-23T18:40:57.67424Z","caller":"traceutil/trace.go:171","msg":"trace[313213036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2197; }","duration":"613.246549ms","start":"2026-06-23T18:40:57.060972Z","end":"2026-06-23T18:40:57.674219Z","steps":["trace[313213036] 'agreement among raft nodes before linearized reading' (duration: 612.59107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.674304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:57.060947Z","time spent":"613.336041ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-23T18:40:57.903358Z","caller":"traceutil/trace.go:171","msg":"trace[1542210808] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2287; }","duration":"220.117322ms","start":"2026-06-23T18:40:57.683181Z","end":"2026-06-23T18:40:57.903298Z","steps":["trace[1542210808] 'read index received' (duration: 2.173041ms)","trace[1542210808] 'applied index is now lower than readState.Index' (duration: 217.942951ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:57.903645Z","caller":"traceutil/trace.go:171","msg":"trace[1833349904] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"223.721782ms","start":"2026-06-23T18:40:57.679909Z","end":"2026-06-23T18:40:57.903631Z","steps":["trace[1833349904] 'process raft request' (duration: 223.269895ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.90366Z","caller":"traceutil/trace.go:171","msg":"trace[375502816] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"224.908309ms","start":"2026-06-23T18:40:57.678741Z","end":"2026-06-23T18:40:57.903649Z","steps":["trace[375502816] 'process raft request' (duration: 129.051005ms)","trace[375502816] 'compare' (duration: 95.015582ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:57.903414Z","caller":"traceutil/trace.go:171","msg":"trace[1069976737] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"217.533116ms","start":"2026-06-23T18:40:57.685859Z","end":"2026-06-23T18:40:57.903392Z","steps":["trace[1069976737] 'process raft request' (duration: 217.401165ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.90392Z","caller":"traceutil/trace.go:171","msg":"trace[2107577203] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"224.897529ms","start":"2026-06-23T18:40:57.678989Z","end":"2026-06-23T18:40:57.903886Z","steps":["trace[2107577203] 'process raft request' (duration: 224.096727ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.904021Z","caller":"traceutil/trace.go:171","msg":"trace[2037769248] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"222.712249ms","start":"2026-06-23T18:40:57.6813Z","end":"2026-06-23T18:40:57.904012Z","steps":["trace[2037769248] 'process raft request' (duration: 221.917578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:57.904126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.937743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:40:57.904154Z","caller":"traceutil/trace.go:171","msg":"trace[88129641] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2203; }","duration":"220.996234ms","start":"2026-06-23T18:40:57.683151Z","end":"2026-06-23T18:40:57.904147Z","steps":["trace[88129641] 'agreement among raft nodes before linearized reading' (duration: 220.932713ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:57.904382Z","caller":"traceutil/trace.go:171","msg":"trace[1841746488] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"225.316745ms","start":"2026-06-23T18:40:57.679053Z","end":"2026-06-23T18:40:57.90437Z","steps":["trace[1841746488] 'process raft request' (duration: 224.086367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:34.434253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.881174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-23T18:41:34.434321Z","caller":"traceutil/trace.go:171","msg":"trace[554618585] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2448; }","duration":"105.996835ms","start":"2026-06-23T18:41:34.32831Z","end":"2026-06-23T18:41:34.434307Z","steps":["trace[554618585] 'range keys from in-memory index tree' (duration: 105.706201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:43.09189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.119557ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-23T18:41:43.092254Z","caller":"traceutil/trace.go:171","msg":"trace[344672586] linearizableReadLoop","detail":"{readStateIndex:2649; appliedIndex:2647; }","duration":"250.077896ms","start":"2026-06-23T18:41:42.842146Z","end":"2026-06-23T18:41:43.092224Z","steps":["trace[344672586] 'read index received' (duration: 164.123285ms)","trace[344672586] 'applied index is now lower than readState.Index' (duration: 85.953101ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:41:43.092378Z","caller":"traceutil/trace.go:171","msg":"trace[1887249944] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"480.03456ms","start":"2026-06-23T18:41:42.612329Z","end":"2026-06-23T18:41:43.092363Z","steps":["trace[1887249944] 'process raft request' (duration: 479.712166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:43.092504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:41:42.612313Z","time spent":"480.095021ms","remote":"127.0.0.1:40322","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-06-23T18:41:43.092601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.42425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:41:43.092645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.962656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-23T18:41:43.092683Z","caller":"traceutil/trace.go:171","msg":"trace[1870881639] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2550; }","duration":"250.583551ms","start":"2026-06-23T18:41:42.842079Z","end":"2026-06-23T18:41:43.092662Z","steps":["trace[1870881639] 'agreement among raft nodes before linearized reading' (duration: 250.408729ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:43.092704Z","caller":"traceutil/trace.go:171","msg":"trace[1499630642] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2550; }","duration":"237.060957ms","start":"2026-06-23T18:41:42.855628Z","end":"2026-06-23T18:41:43.092689Z","steps":["trace[1499630642] 'agreement among raft nodes before linearized reading' (duration: 236.882844ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:43.092743Z","caller":"traceutil/trace.go:171","msg":"trace[587571382] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"150.78016ms","start":"2026-06-23T18:41:42.94194Z","end":"2026-06-23T18:41:43.09272Z","steps":["trace[587571382] 'process raft request' (duration: 150.327804ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:43.0929Z","caller":"traceutil/trace.go:171","msg":"trace[1053137306] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"125.358381ms","start":"2026-06-23T18:41:42.967532Z","end":"2026-06-23T18:41:43.09289Z","steps":["trace[1053137306] 'process raft request' (duration: 124.933196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:43.392185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.697128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:41:43.392269Z","caller":"traceutil/trace.go:171","msg":"trace[795097598] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2550; }","duration":"143.836399ms","start":"2026-06-23T18:41:43.248413Z","end":"2026-06-23T18:41:43.39225Z","steps":["trace[795097598] 'count revisions from in-memory index tree' (duration: 143.537635ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:43.392862Z","caller":"traceutil/trace.go:171","msg":"trace[607971388] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"115.139308ms","start":"2026-06-23T18:41:43.277708Z","end":"2026-06-23T18:41:43.392847Z","steps":["trace[607971388] 'process raft request' (duration: 113.468626ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:10.424532Z","caller":"traceutil/trace.go:171","msg":"trace[357617830] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"139.309696ms","start":"2026-06-23T18:42:10.285193Z","end":"2026-06-23T18:42:10.424502Z","steps":["trace[357617830] 'process raft request' (duration: 102.210023ms)","trace[357617830] 'compare' (duration: 36.903817ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:42:10.424953Z","caller":"traceutil/trace.go:171","msg":"trace[46742027] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"139.613308ms","start":"2026-06-23T18:42:10.285303Z","end":"2026-06-23T18:42:10.424917Z","steps":["trace[46742027] 'process raft request' (duration: 139.148871ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:10.425696Z","caller":"traceutil/trace.go:171","msg":"trace[994767519] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"136.489203ms","start":"2026-06-23T18:42:10.289185Z","end":"2026-06-23T18:42:10.425674Z","steps":["trace[994767519] 'process raft request' (duration: 135.684416ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:10.425944Z","caller":"traceutil/trace.go:171","msg":"trace[2127898004] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"135.121937ms","start":"2026-06-23T18:42:10.290789Z","end":"2026-06-23T18:42:10.425911Z","steps":["trace[2127898004] 'process raft request' (duration: 134.848678ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:16.075134Z","caller":"traceutil/trace.go:171","msg":"trace[276062857] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"143.441692ms","start":"2026-06-23T18:42:15.93166Z","end":"2026-06-23T18:42:16.075101Z","steps":["trace[276062857] 'process raft request' (duration: 142.573003ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:16.256091Z","caller":"traceutil/trace.go:171","msg":"trace[1665160345] linearizableReadLoop","detail":"{readStateIndex:2837; appliedIndex:2836; }","duration":"110.444583ms","start":"2026-06-23T18:42:16.14561Z","end":"2026-06-23T18:42:16.256055Z","steps":["trace[1665160345] 'read index received' (duration: 47.82183ms)","trace[1665160345] 'applied index is now lower than readState.Index' (duration: 62.621533ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:42:16.256238Z","caller":"traceutil/trace.go:171","msg":"trace[39622094] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"176.8555ms","start":"2026-06-23T18:42:16.079353Z","end":"2026-06-23T18:42:16.256208Z","steps":["trace[39622094] 'process raft request' (duration: 114.221847ms)","trace[39622094] 'compare' (duration: 62.20467ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:42:16.256348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.723122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18bbc9f1632a7e75\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-23T18:42:16.256416Z","caller":"traceutil/trace.go:171","msg":"trace[829668551] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18bbc9f1632a7e75; range_end:; response_count:1; response_revision:2729; }","duration":"110.843916ms","start":"2026-06-23T18:42:16.145551Z","end":"2026-06-23T18:42:16.256395Z","steps":["trace[829668551] 'agreement among raft nodes before linearized reading' (duration: 110.611148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.279923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.126449ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:42:17.28009Z","caller":"traceutil/trace.go:171","msg":"trace[445581743] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2730; }","duration":"370.298054ms","start":"2026-06-23T18:42:16.909759Z","end":"2026-06-23T18:42:17.280057Z","steps":["trace[445581743] 'range keys from in-memory index tree' (duration: 370.110548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.281324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.222586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:42:17.281408Z","caller":"traceutil/trace.go:171","msg":"trace[2116551111] linearizableReadLoop","detail":"{readStateIndex:2839; appliedIndex:2838; }","duration":"823.015555ms","start":"2026-06-23T18:42:16.458379Z","end":"2026-06-23T18:42:17.281394Z","steps":["trace[2116551111] 'read index received' (duration: 260.425357ms)","trace[2116551111] 'applied index is now lower than readState.Index' (duration: 562.588598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:42:17.281585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.205021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-23T18:42:17.281561Z","caller":"traceutil/trace.go:171","msg":"trace[585516581] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"943.166396ms","start":"2026-06-23T18:42:16.33834Z","end":"2026-06-23T18:42:17.281506Z","steps":["trace[585516581] 'process raft request' (duration: 380.656401ms)","trace[585516581] 'compare' (duration: 560.705187ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:42:17.281624Z","caller":"traceutil/trace.go:171","msg":"trace[1672445609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2731; }","duration":"823.278784ms","start":"2026-06-23T18:42:16.458335Z","end":"2026-06-23T18:42:17.281614Z","steps":["trace[1672445609] 'agreement among raft nodes before linearized reading' (duration: 823.13125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.281665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.458312Z","time spent":"823.341246ms","remote":"127.0.0.1:40322","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-06-23T18:42:17.281822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.338301Z","time spent":"943.378913ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:42:17.433554Z","caller":"traceutil/trace.go:171","msg":"trace[1277872119] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"173.774906ms","start":"2026-06-23T18:42:17.259732Z","end":"2026-06-23T18:42:17.433507Z","steps":["trace[1277872119] 'process raft request' (duration: 173.471996ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:17.434989Z","caller":"traceutil/trace.go:171","msg":"trace[1473015388] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"170.8658ms","start":"2026-06-23T18:42:17.264097Z","end":"2026-06-23T18:42:17.434963Z","steps":["trace[1473015388] 'process raft request' (duration: 170.653694ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:17.43555Z","caller":"traceutil/trace.go:171","msg":"trace[1470110725] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2839; }","duration":"154.098196ms","start":"2026-06-23T18:42:17.281436Z","end":"2026-06-23T18:42:17.435534Z","steps":["trace[1470110725] 'read index received' (duration: 151.998227ms)","trace[1470110725] 'applied index is now lower than readState.Index' (duration: 2.098829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:42:17.435738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.277307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T18:42:17.43578Z","caller":"traceutil/trace.go:171","msg":"trace[768449094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2734; }","duration":"977.39346ms","start":"2026-06-23T18:42:16.458374Z","end":"2026-06-23T18:42:17.435768Z","steps":["trace[768449094] 'agreement among raft nodes before linearized reading' (duration: 977.212295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.435814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.458354Z","time spent":"977.450503ms","remote":"127.0.0.1:40322","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-06-23T18:42:17.435824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.583254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:42:17.435885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.099991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-23T18:42:17.435943Z","caller":"traceutil/trace.go:171","msg":"trace[1240148265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2734; }","duration":"977.184664ms","start":"2026-06-23T18:42:16.458745Z","end":"2026-06-23T18:42:17.43593Z","steps":["trace[1240148265] 'agreement among raft nodes before linearized reading' (duration: 977.021288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.435977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.458736Z","time spent":"977.235576ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-23T18:42:17.435993Z","caller":"traceutil/trace.go:171","msg":"trace[1652759583] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"148.417872ms","start":"2026-06-23T18:42:17.287561Z","end":"2026-06-23T18:42:17.435979Z","steps":["trace[1652759583] 'process raft request' (duration: 147.339417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.436022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.507364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-23T18:42:17.436055Z","caller":"traceutil/trace.go:171","msg":"trace[494340659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2734; }","duration":"977.593777ms","start":"2026-06-23T18:42:16.458451Z","end":"2026-06-23T18:42:17.436045Z","steps":["trace[494340659] 'agreement among raft nodes before linearized reading' (duration: 977.499374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.436083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.458422Z","time spent":"977.655439ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T18:42:17.43613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.79706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-23T18:42:17.436147Z","caller":"traceutil/trace.go:171","msg":"trace[109260005] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2734; }","duration":"580.856651ms","start":"2026-06-23T18:42:16.855285Z","end":"2026-06-23T18:42:17.436142Z","steps":["trace[109260005] 'agreement among raft nodes before linearized reading' (duration: 580.789229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.436161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.855253Z","time spent":"580.905313ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-06-23T18:42:17.435932Z","caller":"traceutil/trace.go:171","msg":"trace[1279150403] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2734; }","duration":"489.736889ms","start":"2026-06-23T18:42:16.946166Z","end":"2026-06-23T18:42:17.435903Z","steps":["trace[1279150403] 'agreement among raft nodes before linearized reading' (duration: 489.493631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:17.436247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:16.946146Z","time spent":"490.094041ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:42:17.436244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.372065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-23T18:42:17.436279Z","caller":"traceutil/trace.go:171","msg":"trace[1072293520] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:2734; }","duration":"210.441277ms","start":"2026-06-23T18:42:17.225828Z","end":"2026-06-23T18:42:17.436269Z","steps":["trace[1072293520] 'agreement among raft nodes before linearized reading' (duration: 210.354894ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:22.451607Z","caller":"traceutil/trace.go:171","msg":"trace[702722052] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"158.699076ms","start":"2026-06-23T18:42:22.292855Z","end":"2026-06-23T18:42:22.451554Z","steps":["trace[702722052] 'process raft request' (duration: 158.469089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:44:47.46479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.2933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-23T18:44:47.464916Z","caller":"traceutil/trace.go:171","msg":"trace[1978433421] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3615; }","duration":"100.484414ms","start":"2026-06-23T18:44:47.364403Z","end":"2026-06-23T18:44:47.464887Z","steps":["trace[1978433421] 'range keys from in-memory index tree' (duration: 100.058905ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:44:58.582969Z","caller":"traceutil/trace.go:171","msg":"trace[1018070783] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"189.925478ms","start":"2026-06-23T18:44:58.393012Z","end":"2026-06-23T18:44:58.582937Z","steps":["trace[1018070783] 'process raft request' (duration: 165.99451ms)","trace[1018070783] 'compare' (duration: 22.897408ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:49.873128Z","caller":"traceutil/trace.go:171","msg":"trace[257254642] transaction","detail":"{read_only:false; response_revision:4020; number_of_response:1; }","duration":"118.782096ms","start":"2026-06-23T18:45:49.754298Z","end":"2026-06-23T18:45:49.87308Z","steps":["trace[257254642] 'process raft request' (duration: 85.286998ms)","trace[257254642] 'compare' (duration: 33.122022ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:59.315963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.898842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:2856"} {"level":"info","ts":"2026-06-23T18:45:59.316055Z","caller":"traceutil/trace.go:171","msg":"trace[1844845979] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4415; }","duration":"109.032004ms","start":"2026-06-23T18:45:59.207003Z","end":"2026-06-23T18:45:59.316035Z","steps":["trace[1844845979] 'range keys from in-memory index tree' (duration: 108.82556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:01.54204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.630553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:01.542141Z","caller":"traceutil/trace.go:171","msg":"trace[1346826036] linearizableReadLoop","detail":"{readStateIndex:4622; appliedIndex:4621; }","duration":"161.630524ms","start":"2026-06-23T18:46:01.380496Z","end":"2026-06-23T18:46:01.542126Z","steps":["trace[1346826036] 'read index received' (duration: 32.760647ms)","trace[1346826036] 'applied index is now lower than readState.Index' (duration: 128.868627ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:01.542436Z","caller":"traceutil/trace.go:171","msg":"trace[807664009] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"218.022476ms","start":"2026-06-23T18:46:01.324387Z","end":"2026-06-23T18:46:01.542409Z","steps":["trace[807664009] 'process raft request' (duration: 88.908315ms)","trace[807664009] 'compare' (duration: 128.419369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:01.542529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.011131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T18:46:01.542737Z","caller":"traceutil/trace.go:171","msg":"trace[2048485846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4444; }","duration":"162.264075ms","start":"2026-06-23T18:46:01.380461Z","end":"2026-06-23T18:46:01.542725Z","steps":["trace[2048485846] 'agreement among raft nodes before linearized reading' (duration: 161.880038ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:03.712268Z","caller":"traceutil/trace.go:171","msg":"trace[1451114620] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"423.94438ms","start":"2026-06-23T18:46:03.288298Z","end":"2026-06-23T18:46:03.712242Z","steps":["trace[1451114620] 'process raft request' (duration: 423.803678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.712408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:03.288278Z","time spent":"424.055722ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3878,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:46:03.838695Z","caller":"traceutil/trace.go:171","msg":"trace[1930829947] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"535.819221ms","start":"2026-06-23T18:46:03.302843Z","end":"2026-06-23T18:46:03.838662Z","steps":["trace[1930829947] 'process raft request' (duration: 494.117119ms)","trace[1930829947] 'compare' (duration: 41.431018ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:03.838698Z","caller":"traceutil/trace.go:171","msg":"trace[1044357458] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4702; }","duration":"380.476617ms","start":"2026-06-23T18:46:03.458194Z","end":"2026-06-23T18:46:03.838671Z","steps":["trace[1044357458] 'read index received' (duration: 253.953057ms)","trace[1044357458] 'applied index is now lower than readState.Index' (duration: 126.52266ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:03.838857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:03.302824Z","time spent":"535.964466ms","remote":"127.0.0.1:40300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:03.83905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.962843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:46:03.839149Z","caller":"traceutil/trace.go:171","msg":"trace[168378893] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4527; }","duration":"105.126505ms","start":"2026-06-23T18:46:03.73399Z","end":"2026-06-23T18:46:03.839116Z","steps":["trace[168378893] 'agreement among raft nodes before linearized reading' (duration: 104.986423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.83915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.25131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-23T18:46:03.839153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.48136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c30750e7-90cf-4866-8014-1756ea069d48\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-06-23T18:46:03.839203Z","caller":"traceutil/trace.go:171","msg":"trace[1807972189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4527; }","duration":"279.335902ms","start":"2026-06-23T18:46:03.559853Z","end":"2026-06-23T18:46:03.839189Z","steps":["trace[1807972189] 'agreement among raft nodes before linearized reading' (duration: 279.113648ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:03.839234Z","caller":"traceutil/trace.go:171","msg":"trace[650409292] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c30750e7-90cf-4866-8014-1756ea069d48; range_end:; response_count:1; response_revision:4527; }","duration":"358.620313ms","start":"2026-06-23T18:46:03.480594Z","end":"2026-06-23T18:46:03.839214Z","steps":["trace[650409292] 'agreement among raft nodes before linearized reading' (duration: 358.369299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.839289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:03.480567Z","time spent":"358.709614ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3917,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c30750e7-90cf-4866-8014-1756ea069d48\" "} {"level":"warn","ts":"2026-06-23T18:46:03.839321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.734469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T18:46:03.83936Z","caller":"traceutil/trace.go:171","msg":"trace[40013623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4527; }","duration":"279.774689ms","start":"2026-06-23T18:46:03.559575Z","end":"2026-06-23T18:46:03.839349Z","steps":["trace[40013623] 'agreement among raft nodes before linearized reading' (duration: 279.624927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.839499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.397242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-23T18:46:03.839548Z","caller":"traceutil/trace.go:171","msg":"trace[2110183641] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4527; }","duration":"222.472183ms","start":"2026-06-23T18:46:03.617062Z","end":"2026-06-23T18:46:03.839534Z","steps":["trace[2110183641] 'agreement among raft nodes before linearized reading' (duration: 222.364622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.83957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.809857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-23T18:46:03.839615Z","caller":"traceutil/trace.go:171","msg":"trace[1958867820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4527; }","duration":"280.886949ms","start":"2026-06-23T18:46:03.558718Z","end":"2026-06-23T18:46:03.839605Z","steps":["trace[1958867820] 'agreement among raft nodes before linearized reading' (duration: 280.747366ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:03.839663Z","caller":"traceutil/trace.go:171","msg":"trace[958604602] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"291.742099ms","start":"2026-06-23T18:46:03.547904Z","end":"2026-06-23T18:46:03.839646Z","steps":["trace[958604602] 'process raft request' (duration: 290.715442ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:03.839779Z","caller":"traceutil/trace.go:171","msg":"trace[789411328] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"536.369951ms","start":"2026-06-23T18:46:03.30339Z","end":"2026-06-23T18:46:03.83976Z","steps":["trace[789411328] 'process raft request' (duration: 535.15631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.839875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:03.303385Z","time spent":"536.434152ms","remote":"127.0.0.1:38614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:46:03.842054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.849966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-23T18:46:03.842818Z","caller":"traceutil/trace.go:171","msg":"trace[1996114771] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4527; }","duration":"384.64363ms","start":"2026-06-23T18:46:03.458155Z","end":"2026-06-23T18:46:03.842799Z","steps":["trace[1996114771] 'agreement among raft nodes before linearized reading' (duration: 380.763142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:03.843074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:03.458141Z","time spent":"384.900714ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206990,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-06-23T18:46:08.74262Z","caller":"traceutil/trace.go:171","msg":"trace[659755667] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"178.922048ms","start":"2026-06-23T18:46:08.563676Z","end":"2026-06-23T18:46:08.742598Z","steps":["trace[659755667] 'process raft request' (duration: 178.744035ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:08.77237Z","caller":"traceutil/trace.go:171","msg":"trace[57232383] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4827; }","duration":"187.724471ms","start":"2026-06-23T18:46:08.584627Z","end":"2026-06-23T18:46:08.772351Z","steps":["trace[57232383] 'read index received' (duration: 157.79901ms)","trace[57232383] 'applied index is now lower than readState.Index' (duration: 29.924811ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:08.77258Z","caller":"traceutil/trace.go:171","msg":"trace[1080933136] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"206.076392ms","start":"2026-06-23T18:46:08.566496Z","end":"2026-06-23T18:46:08.772572Z","steps":["trace[1080933136] 'process raft request' (duration: 205.761937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:08.772766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.143309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T18:46:08.772788Z","caller":"traceutil/trace.go:171","msg":"trace[926678644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4646; }","duration":"188.20866ms","start":"2026-06-23T18:46:08.584574Z","end":"2026-06-23T18:46:08.772783Z","steps":["trace[926678644] 'agreement among raft nodes before linearized reading' (duration: 188.108608ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:09.544936Z","caller":"traceutil/trace.go:171","msg":"trace[2006052164] linearizableReadLoop","detail":"{readStateIndex:4831; appliedIndex:4830; }","duration":"300.004312ms","start":"2026-06-23T18:46:09.244908Z","end":"2026-06-23T18:46:09.544912Z","steps":["trace[2006052164] 'read index received' (duration: 299.842369ms)","trace[2006052164] 'applied index is now lower than readState.Index' (duration: 160.843µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:09.545038Z","caller":"traceutil/trace.go:171","msg":"trace[245152915] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"528.191695ms","start":"2026-06-23T18:46:09.016812Z","end":"2026-06-23T18:46:09.545004Z","steps":["trace[245152915] 'process raft request' (duration: 527.9389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:09.54526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:09.016791Z","time spent":"528.387249ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:09.54525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.311887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-23T18:46:09.5455Z","caller":"traceutil/trace.go:171","msg":"trace[382679056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4648; }","duration":"300.580842ms","start":"2026-06-23T18:46:09.2449Z","end":"2026-06-23T18:46:09.545481Z","steps":["trace[382679056] 'agreement among raft nodes before linearized reading' (duration: 300.125944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:09.545554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:09.244843Z","time spent":"300.700074ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-23T18:46:09.552628Z","caller":"traceutil/trace.go:171","msg":"trace[525692139] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"120.836303ms","start":"2026-06-23T18:46:09.431777Z","end":"2026-06-23T18:46:09.552613Z","steps":["trace[525692139] 'process raft request' (duration: 120.784742ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:09.552812Z","caller":"traceutil/trace.go:171","msg":"trace[853148599] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"201.099001ms","start":"2026-06-23T18:46:09.351703Z","end":"2026-06-23T18:46:09.552802Z","steps":["trace[853148599] 'process raft request' (duration: 200.768224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:09.553485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.264989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-s2h4m\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-06-23T18:46:09.553534Z","caller":"traceutil/trace.go:171","msg":"trace[1279616609] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-s2h4m; range_end:; response_count:1; response_revision:4650; }","duration":"203.34305ms","start":"2026-06-23T18:46:09.350178Z","end":"2026-06-23T18:46:09.553521Z","steps":["trace[1279616609] 'agreement among raft nodes before linearized reading' (duration: 203.214678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:14.325565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.646265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:14.325712Z","caller":"traceutil/trace.go:171","msg":"trace[723045105] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"270.64449ms","start":"2026-06-23T18:46:14.055004Z","end":"2026-06-23T18:46:14.325648Z","steps":["trace[723045105] 'read index received' (duration: 4.779542ms)","trace[723045105] 'applied index is now lower than readState.Index' (duration: 265.863168ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:14.325808Z","caller":"traceutil/trace.go:171","msg":"trace[1921732614] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"372.137057ms","start":"2026-06-23T18:46:13.95364Z","end":"2026-06-23T18:46:14.325777Z","steps":["trace[1921732614] 'process raft request' (duration: 106.195217ms)","trace[1921732614] 'compare' (duration: 265.477402ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:14.325946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:13.953615Z","time spent":"372.256778ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:14.325969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.313606ms","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-06-23T18:46:14.326007Z","caller":"traceutil/trace.go:171","msg":"trace[778806981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4736; }","duration":"266.374187ms","start":"2026-06-23T18:46:14.05962Z","end":"2026-06-23T18:46:14.325994Z","steps":["trace[778806981] 'agreement among raft nodes before linearized reading' (duration: 266.257355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:14.326015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.960366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-23T18:46:14.326054Z","caller":"traceutil/trace.go:171","msg":"trace[962119436] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded; range_end:; response_count:1; response_revision:4736; }","duration":"271.080097ms","start":"2026-06-23T18:46:14.054961Z","end":"2026-06-23T18:46:14.326041Z","steps":["trace[962119436] 'agreement among raft nodes before linearized reading' (duration: 270.854173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:14.326096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.818769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18bbca2d329ba8f9\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-06-23T18:46:14.32613Z","caller":"traceutil/trace.go:171","msg":"trace[1862664110] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18bbca2d329ba8f9; range_end:; response_count:1; response_revision:4736; }","duration":"105.88678ms","start":"2026-06-23T18:46:14.220233Z","end":"2026-06-23T18:46:14.326119Z","steps":["trace[1862664110] 'agreement among raft nodes before linearized reading' (duration: 105.798529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.029642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.566541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:15.029813Z","caller":"traceutil/trace.go:171","msg":"trace[1619998798] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"700.326401ms","start":"2026-06-23T18:46:14.329464Z","end":"2026-06-23T18:46:15.02979Z","steps":["trace[1619998798] 'process raft request' (duration: 282.435174ms)","trace[1619998798] 'compare' (duration: 416.880539ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:15.029925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.329446Z","time spent":"700.431402ms","remote":"127.0.0.1:40112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:46:15.088942Z","caller":"traceutil/trace.go:171","msg":"trace[453651394] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4921; }","duration":"699.842191ms","start":"2026-06-23T18:46:14.389074Z","end":"2026-06-23T18:46:15.088917Z","steps":["trace[453651394] 'read index received' (duration: 222.838103ms)","trace[453651394] 'applied index is now lower than readState.Index' (duration: 477.003058ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:15.089085Z","caller":"traceutil/trace.go:171","msg":"trace[1670189550] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"758.989674ms","start":"2026-06-23T18:46:14.330084Z","end":"2026-06-23T18:46:15.089074Z","steps":["trace[1670189550] 'process raft request' (duration: 758.674119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.089217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.330069Z","time spent":"759.045626ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:15.08977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.682477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-23T18:46:15.089819Z","caller":"traceutil/trace.go:171","msg":"trace[570368564] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4738; }","duration":"700.768327ms","start":"2026-06-23T18:46:14.389038Z","end":"2026-06-23T18:46:15.089807Z","steps":["trace[570368564] 'agreement among raft nodes before linearized reading' (duration: 700.30515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.089855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.389019Z","time spent":"700.827548ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":456,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:46:15.115437Z","caller":"traceutil/trace.go:171","msg":"trace[526115114] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"482.979419ms","start":"2026-06-23T18:46:14.632435Z","end":"2026-06-23T18:46:15.115415Z","steps":["trace[526115114] 'process raft request' (duration: 482.733755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.11555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.63241Z","time spent":"483.075001ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:15.115606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.632988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-06-23T18:46:15.115641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.099904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:46:15.11566Z","caller":"traceutil/trace.go:171","msg":"trace[2099508955] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4741; }","duration":"349.118465ms","start":"2026-06-23T18:46:14.766536Z","end":"2026-06-23T18:46:15.115655Z","steps":["trace[2099508955] 'agreement among raft nodes before linearized reading' (duration: 349.086054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.115676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.766513Z","time spent":"349.158975ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:46:15.115667Z","caller":"traceutil/trace.go:171","msg":"trace[789014981] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded; range_end:; response_count:1; response_revision:4741; }","duration":"717.713339ms","start":"2026-06-23T18:46:14.39794Z","end":"2026-06-23T18:46:15.115653Z","steps":["trace[789014981] 'agreement among raft nodes before linearized reading' (duration: 717.528946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.115713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.409965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T18:46:15.11582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.39793Z","time spent":"717.856462ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded\" "} {"level":"warn","ts":"2026-06-23T18:46:15.115853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.931509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-23T18:46:15.115932Z","caller":"traceutil/trace.go:171","msg":"trace[561754802] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4741; }","duration":"394.115652ms","start":"2026-06-23T18:46:14.721802Z","end":"2026-06-23T18:46:15.115917Z","steps":["trace[561754802] 'agreement among raft nodes before linearized reading' (duration: 393.754426ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:15.115923Z","caller":"traceutil/trace.go:171","msg":"trace[1951754699] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4741; }","duration":"602.618109ms","start":"2026-06-23T18:46:14.513203Z","end":"2026-06-23T18:46:15.115821Z","steps":["trace[1951754699] 'agreement among raft nodes before linearized reading' (duration: 602.484287ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:15.115996Z","caller":"traceutil/trace.go:171","msg":"trace[312871804] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"346.884136ms","start":"2026-06-23T18:46:14.769083Z","end":"2026-06-23T18:46:15.115967Z","steps":["trace[312871804] 'process raft request' (duration: 346.259975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.116092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.513184Z","time spent":"602.800122ms","remote":"127.0.0.1:54334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:46:15.116114Z","caller":"traceutil/trace.go:171","msg":"trace[775700813] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"320.244055ms","start":"2026-06-23T18:46:14.795862Z","end":"2026-06-23T18:46:15.116106Z","steps":["trace[775700813] 'process raft request' (duration: 319.533893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.116105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.769055Z","time spent":"346.985987ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:15.116153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.795842Z","time spent":"320.289497ms","remote":"127.0.0.1:40322","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-06-23T18:46:15.116005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.721785Z","time spent":"394.211494ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-23T18:46:15.116271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.597951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T18:46:15.116321Z","caller":"traceutil/trace.go:171","msg":"trace[1737931084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4741; }","duration":"276.674903ms","start":"2026-06-23T18:46:14.839638Z","end":"2026-06-23T18:46:15.116313Z","steps":["trace[1737931084] 'agreement among raft nodes before linearized reading' (duration: 276.561121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.115858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.143319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:15.11645Z","caller":"traceutil/trace.go:171","msg":"trace[1669019643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4741; }","duration":"311.731049ms","start":"2026-06-23T18:46:14.804668Z","end":"2026-06-23T18:46:15.116399Z","steps":["trace[1669019643] 'agreement among raft nodes before linearized reading' (duration: 310.844353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.116503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:14.804648Z","time spent":"311.840871ms","remote":"127.0.0.1:40036","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-06-23T18:46:15.904252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.557352ms","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-06-23T18:46:15.90436Z","caller":"traceutil/trace.go:171","msg":"trace[145377497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4743; }","duration":"311.743245ms","start":"2026-06-23T18:46:15.592591Z","end":"2026-06-23T18:46:15.904335Z","steps":["trace[145377497] 'range keys from in-memory index tree' (duration: 311.276507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.904413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.264332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T18:46:15.904467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:15.592565Z","time spent":"311.889497ms","remote":"127.0.0.1:40322","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-06-23T18:46:15.904498Z","caller":"traceutil/trace.go:171","msg":"trace[1185650752] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4743; }","duration":"511.390214ms","start":"2026-06-23T18:46:15.393088Z","end":"2026-06-23T18:46:15.904478Z","steps":["trace[1185650752] 'count revisions from in-memory index tree' (duration: 511.146409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.90455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:15.393069Z","time spent":"511.469975ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:46:15.904566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.825355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:46:15.904627Z","caller":"traceutil/trace.go:171","msg":"trace[598444365] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4743; }","duration":"363.922177ms","start":"2026-06-23T18:46:15.540688Z","end":"2026-06-23T18:46:15.90461Z","steps":["trace[598444365] 'count revisions from in-memory index tree' (duration: 363.728223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.904706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:15.540657Z","time spent":"364.002317ms","remote":"127.0.0.1:40590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:46:15.904284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.999655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-23T18:46:15.905006Z","caller":"traceutil/trace.go:171","msg":"trace[98010645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded; range_end:; response_count:1; response_revision:4743; }","duration":"574.699146ms","start":"2026-06-23T18:46:15.330251Z","end":"2026-06-23T18:46:15.90495Z","steps":["trace[98010645] 'range keys from in-memory index tree' (duration: 573.784401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:15.905087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:15.33017Z","time spent":"574.89962ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d60ce881-09ea-480a-9c45-1b3d1c9f8ded\" "} {"level":"info","ts":"2026-06-23T18:46:17.271937Z","caller":"traceutil/trace.go:171","msg":"trace[994735537] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"151.103943ms","start":"2026-06-23T18:46:17.120811Z","end":"2026-06-23T18:46:17.271915Z","steps":["trace[994735537] 'process raft request' (duration: 150.686596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:17.271946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.498338ms","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-06-23T18:46:17.272036Z","caller":"traceutil/trace.go:171","msg":"trace[536520735] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4759; }","duration":"177.63537ms","start":"2026-06-23T18:46:17.09438Z","end":"2026-06-23T18:46:17.272015Z","steps":["trace[536520735] 'range keys from in-memory index tree' (duration: 177.319625ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:17.27269Z","caller":"traceutil/trace.go:171","msg":"trace[1174784763] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4944; }","duration":"140.940268ms","start":"2026-06-23T18:46:17.131734Z","end":"2026-06-23T18:46:17.272674Z","steps":["trace[1174784763] 'read index received' (duration: 139.775639ms)","trace[1174784763] 'applied index is now lower than readState.Index' (duration: 1.163479ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:17.272787Z","caller":"traceutil/trace.go:171","msg":"trace[232951781] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"151.571032ms","start":"2026-06-23T18:46:17.121206Z","end":"2026-06-23T18:46:17.272777Z","steps":["trace[232951781] 'process raft request' (duration: 151.384029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:17.273122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.357495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T18:46:17.273179Z","caller":"traceutil/trace.go:171","msg":"trace[1118807575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4761; }","duration":"141.464557ms","start":"2026-06-23T18:46:17.131701Z","end":"2026-06-23T18:46:17.273165Z","steps":["trace[1118807575] 'agreement among raft nodes before linearized reading' (duration: 141.236123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:25.628693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.969396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:25.628978Z","caller":"traceutil/trace.go:171","msg":"trace[1393745765] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"160.266535ms","start":"2026-06-23T18:46:25.468695Z","end":"2026-06-23T18:46:25.628962Z","steps":["trace[1393745765] 'process raft request' (duration: 160.229064ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:25.62915Z","caller":"traceutil/trace.go:171","msg":"trace[198771136] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"199.582245ms","start":"2026-06-23T18:46:25.429542Z","end":"2026-06-23T18:46:25.629125Z","steps":["trace[198771136] 'process raft request' (duration: 199.345021ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:25.629277Z","caller":"traceutil/trace.go:171","msg":"trace[937999932] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5077; }","duration":"239.907573ms","start":"2026-06-23T18:46:25.389329Z","end":"2026-06-23T18:46:25.629237Z","steps":["trace[937999932] 'read index received' (duration: 61.322206ms)","trace[937999932] 'applied index is now lower than readState.Index' (duration: 178.582757ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:25.629147Z","caller":"traceutil/trace.go:171","msg":"trace[1200812540] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"252.282025ms","start":"2026-06-23T18:46:25.376794Z","end":"2026-06-23T18:46:25.629076Z","steps":["trace[1200812540] 'process raft request' (duration: 252.00569ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:25.629342Z","caller":"traceutil/trace.go:171","msg":"trace[777534053] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"252.63868ms","start":"2026-06-23T18:46:25.376687Z","end":"2026-06-23T18:46:25.629326Z","steps":["trace[777534053] 'process raft request' (duration: 73.950992ms)","trace[777534053] 'compare' (duration: 177.759183ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:25.629477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.143127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:25.629516Z","caller":"traceutil/trace.go:171","msg":"trace[193194157] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4890; }","duration":"240.202698ms","start":"2026-06-23T18:46:25.389302Z","end":"2026-06-23T18:46:25.629504Z","steps":["trace[193194157] 'agreement among raft nodes before linearized reading' (duration: 240.074696ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:25.77419Z","caller":"traceutil/trace.go:171","msg":"trace[1948397154] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"127.280002ms","start":"2026-06-23T18:46:25.646887Z","end":"2026-06-23T18:46:25.774167Z","steps":["trace[1948397154] 'read index received' (duration: 127.072418ms)","trace[1948397154] 'applied index is now lower than readState.Index' (duration: 206.634µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:25.774233Z","caller":"traceutil/trace.go:171","msg":"trace[1487174354] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"132.777455ms","start":"2026-06-23T18:46:25.641428Z","end":"2026-06-23T18:46:25.774205Z","steps":["trace[1487174354] 'process raft request' (duration: 132.583312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:25.774421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.525656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-06-23T18:46:25.774478Z","caller":"traceutil/trace.go:171","msg":"trace[439729302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4891; }","duration":"127.616907ms","start":"2026-06-23T18:46:25.646835Z","end":"2026-06-23T18:46:25.774452Z","steps":["trace[439729302] 'agreement among raft nodes before linearized reading' (duration: 127.447425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:26.954988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.101072ms","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-06-23T18:46:26.955103Z","caller":"traceutil/trace.go:171","msg":"trace[281289317] 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:4901; }","duration":"409.259815ms","start":"2026-06-23T18:46:26.545819Z","end":"2026-06-23T18:46:26.955079Z","steps":["trace[281289317] 'range keys from in-memory index tree' (duration: 408.9339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:26.955162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:26.545799Z","time spent":"409.347956ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-06-23T18:46:26.955735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.393144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:46:26.955796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.901985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T18:46:26.955808Z","caller":"traceutil/trace.go:171","msg":"trace[1219566836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4901; }","duration":"150.495775ms","start":"2026-06-23T18:46:26.805289Z","end":"2026-06-23T18:46:26.955785Z","steps":["trace[1219566836] 'range keys from in-memory index tree' (duration: 150.301342ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:26.955848Z","caller":"traceutil/trace.go:171","msg":"trace[101137706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4901; }","duration":"548.979776ms","start":"2026-06-23T18:46:26.406843Z","end":"2026-06-23T18:46:26.955823Z","steps":["trace[101137706] 'range keys from in-memory index tree' (duration: 548.767853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:26.955879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:26.40682Z","time spent":"549.051297ms","remote":"127.0.0.1:40322","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-06-23T18:46:31.104738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627415166283593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:46:31.512697Z","caller":"traceutil/trace.go:171","msg":"trace[1616736668] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5126; }","duration":"908.082506ms","start":"2026-06-23T18:46:30.604594Z","end":"2026-06-23T18:46:31.512676Z","steps":["trace[1616736668] 'read index received' (duration: 901.512374ms)","trace[1616736668] 'applied index is now lower than readState.Index' (duration: 6.568942ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:31.512935Z","caller":"traceutil/trace.go:171","msg":"trace[680862449] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"940.633197ms","start":"2026-06-23T18:46:30.572292Z","end":"2026-06-23T18:46:31.512926Z","steps":["trace[680862449] 'process raft request' (duration: 933.832991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.513042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:30.572271Z","time spent":"940.689919ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:31.513221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.630255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-23T18:46:31.513246Z","caller":"traceutil/trace.go:171","msg":"trace[147820932] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4935; }","duration":"908.680595ms","start":"2026-06-23T18:46:30.604557Z","end":"2026-06-23T18:46:31.513238Z","steps":["trace[147820932] 'agreement among raft nodes before linearized reading' (duration: 908.594194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.513266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:30.604545Z","time spent":"908.716706ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-23T18:46:31.516797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.112888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-23T18:46:31.51687Z","caller":"traceutil/trace.go:171","msg":"trace[1125010626] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4937; }","duration":"736.222989ms","start":"2026-06-23T18:46:30.78063Z","end":"2026-06-23T18:46:31.516853Z","steps":["trace[1125010626] 'agreement among raft nodes before linearized reading' (duration: 736.011486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.516913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:30.780612Z","time spent":"736.292361ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-23T18:46:31.517429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.769551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:31.517481Z","caller":"traceutil/trace.go:171","msg":"trace[499040902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4937; }","duration":"712.835343ms","start":"2026-06-23T18:46:30.804634Z","end":"2026-06-23T18:46:31.517469Z","steps":["trace[499040902] 'agreement among raft nodes before linearized reading' (duration: 712.765751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.51751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:30.804626Z","time spent":"712.878093ms","remote":"127.0.0.1:40036","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-06-23T18:46:31.517647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.409794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T18:46:31.517734Z","caller":"traceutil/trace.go:171","msg":"trace[206188421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4937; }","duration":"541.587317ms","start":"2026-06-23T18:46:30.976132Z","end":"2026-06-23T18:46:31.51772Z","steps":["trace[206188421] 'agreement among raft nodes before linearized reading' (duration: 541.254371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.517766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:30.976111Z","time spent":"541.647778ms","remote":"127.0.0.1:40322","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-06-23T18:46:31.518053Z","caller":"traceutil/trace.go:171","msg":"trace[1147309702] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"127.142644ms","start":"2026-06-23T18:46:31.390862Z","end":"2026-06-23T18:46:31.518005Z","steps":["trace[1147309702] 'process raft request' (duration: 125.620049ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.51808Z","caller":"traceutil/trace.go:171","msg":"trace[1837467642] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"125.688799ms","start":"2026-06-23T18:46:31.392371Z","end":"2026-06-23T18:46:31.51806Z","steps":["trace[1837467642] 'process raft request' (duration: 124.228455ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.790002Z","caller":"traceutil/trace.go:171","msg":"trace[1262886960] linearizableReadLoop","detail":"{readStateIndex:5130; appliedIndex:5129; }","duration":"270.094357ms","start":"2026-06-23T18:46:31.519882Z","end":"2026-06-23T18:46:31.789976Z","steps":["trace[1262886960] 'read index received' (duration: 224.31914ms)","trace[1262886960] 'applied index is now lower than readState.Index' (duration: 45.774297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:31.79018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.269371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:31.790226Z","caller":"traceutil/trace.go:171","msg":"trace[74009642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4938; }","duration":"270.338552ms","start":"2026-06-23T18:46:31.519875Z","end":"2026-06-23T18:46:31.790214Z","steps":["trace[74009642] 'agreement among raft nodes before linearized reading' (duration: 270.23252ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.790203Z","caller":"traceutil/trace.go:171","msg":"trace[1193224565] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"272.036849ms","start":"2026-06-23T18:46:31.51814Z","end":"2026-06-23T18:46:31.790177Z","steps":["trace[1193224565] 'process raft request' (duration: 226.057879ms)","trace[1193224565] 'compare' (duration: 45.608833ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:31.79984Z","caller":"traceutil/trace.go:171","msg":"trace[2033582559] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"279.894223ms","start":"2026-06-23T18:46:31.519911Z","end":"2026-06-23T18:46:31.799805Z","steps":["trace[2033582559] 'process raft request' (duration: 279.606878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.799999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.058778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-23T18:46:31.800049Z","caller":"traceutil/trace.go:171","msg":"trace[789558256] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4943; }","duration":"279.17258ms","start":"2026-06-23T18:46:31.520865Z","end":"2026-06-23T18:46:31.800037Z","steps":["trace[789558256] 'agreement among raft nodes before linearized reading' (duration: 278.993407ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.800217Z","caller":"traceutil/trace.go:171","msg":"trace[535421518] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"279.444855ms","start":"2026-06-23T18:46:31.52076Z","end":"2026-06-23T18:46:31.800205Z","steps":["trace[535421518] 'process raft request' (duration: 278.997538ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.800229Z","caller":"traceutil/trace.go:171","msg":"trace[2029238446] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"252.510358ms","start":"2026-06-23T18:46:31.547703Z","end":"2026-06-23T18:46:31.800213Z","steps":["trace[2029238446] 'process raft request' (duration: 252.122012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.800273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.613009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-23T18:46:31.800296Z","caller":"traceutil/trace.go:171","msg":"trace[845803551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4943; }","duration":"141.65387ms","start":"2026-06-23T18:46:31.658634Z","end":"2026-06-23T18:46:31.800288Z","steps":["trace[845803551] 'agreement among raft nodes before linearized reading' (duration: 141.590539ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.800313Z","caller":"traceutil/trace.go:171","msg":"trace[1932013144] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"279.663449ms","start":"2026-06-23T18:46:31.520639Z","end":"2026-06-23T18:46:31.800302Z","steps":["trace[1932013144] 'process raft request' (duration: 279.060319ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.800555Z","caller":"traceutil/trace.go:171","msg":"trace[295575863] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"278.050531ms","start":"2026-06-23T18:46:31.522492Z","end":"2026-06-23T18:46:31.800542Z","steps":["trace[295575863] 'process raft request' (duration: 277.291758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.80058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.553929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"warn","ts":"2026-06-23T18:46:31.80061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.116762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T18:46:31.800637Z","caller":"traceutil/trace.go:171","msg":"trace[685952137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4943; }","duration":"144.169722ms","start":"2026-06-23T18:46:31.656461Z","end":"2026-06-23T18:46:31.800631Z","steps":["trace[685952137] 'agreement among raft nodes before linearized reading' (duration: 144.102231ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:31.800646Z","caller":"traceutil/trace.go:171","msg":"trace[786125429] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4943; }","duration":"216.651461ms","start":"2026-06-23T18:46:31.583975Z","end":"2026-06-23T18:46:31.800626Z","steps":["trace[786125429] 'agreement among raft nodes before linearized reading' (duration: 216.419847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:31.800776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.588292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-23T18:46:31.800829Z","caller":"traceutil/trace.go:171","msg":"trace[983566061] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4943; }","duration":"179.693544ms","start":"2026-06-23T18:46:31.62112Z","end":"2026-06-23T18:46:31.800814Z","steps":["trace[983566061] 'agreement among raft nodes before linearized reading' (duration: 179.518831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:32.320023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.69804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:32.320127Z","caller":"traceutil/trace.go:171","msg":"trace[718717249] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"118.067929ms","start":"2026-06-23T18:46:32.20204Z","end":"2026-06-23T18:46:32.320108Z","steps":["trace[718717249] 'read index received' (duration: 46.321µs)","trace[718717249] 'applied index is now lower than readState.Index' (duration: 118.020098ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:32.320177Z","caller":"traceutil/trace.go:171","msg":"trace[967638097] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"177.396283ms","start":"2026-06-23T18:46:32.142736Z","end":"2026-06-23T18:46:32.320132Z","steps":["trace[967638097] 'process raft request' (duration: 39.360067ms)","trace[967638097] 'compare' (duration: 137.220313ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:32.320549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.484896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-23T18:46:32.320633Z","caller":"traceutil/trace.go:171","msg":"trace[1239770653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4949; }","duration":"118.647158ms","start":"2026-06-23T18:46:32.201968Z","end":"2026-06-23T18:46:32.320615Z","steps":["trace[1239770653] 'agreement among raft nodes before linearized reading' (duration: 118.351713ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:32.805486Z","caller":"traceutil/trace.go:171","msg":"trace[1592644519] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"150.179082ms","start":"2026-06-23T18:46:32.655277Z","end":"2026-06-23T18:46:32.805456Z","steps":["trace[1592644519] 'read index received' (duration: 149.858407ms)","trace[1592644519] 'applied index is now lower than readState.Index' (duration: 319.485µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:32.805711Z","caller":"traceutil/trace.go:171","msg":"trace[293813277] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"200.083006ms","start":"2026-06-23T18:46:32.605616Z","end":"2026-06-23T18:46:32.805699Z","steps":["trace[293813277] 'process raft request' (duration: 199.590668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:32.805899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.174419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:32.805987Z","caller":"traceutil/trace.go:171","msg":"trace[1434472709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"115.311571ms","start":"2026-06-23T18:46:32.690654Z","end":"2026-06-23T18:46:32.805965Z","steps":["trace[1434472709] 'agreement among raft nodes before linearized reading' (duration: 115.176859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:32.806363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.091567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-23T18:46:32.806413Z","caller":"traceutil/trace.go:171","msg":"trace[878541140] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4952; }","duration":"151.191049ms","start":"2026-06-23T18:46:32.655208Z","end":"2026-06-23T18:46:32.806399Z","steps":["trace[878541140] 'agreement among raft nodes before linearized reading' (duration: 151.020906ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:38.031259Z","caller":"traceutil/trace.go:171","msg":"trace[864905120] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"128.032554ms","start":"2026-06-23T18:46:37.903201Z","end":"2026-06-23T18:46:38.031233Z","steps":["trace[864905120] 'process raft request' (duration: 127.919632ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:50.473367Z","caller":"traceutil/trace.go:171","msg":"trace[360206396] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5279; }","duration":"187.009106ms","start":"2026-06-23T18:46:50.286288Z","end":"2026-06-23T18:46:50.473297Z","steps":["trace[360206396] 'read index received' (duration: 117.903568ms)","trace[360206396] 'applied index is now lower than readState.Index' (duration: 69.103948ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:50.473573Z","caller":"traceutil/trace.go:171","msg":"trace[159854833] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"191.475359ms","start":"2026-06-23T18:46:50.282077Z","end":"2026-06-23T18:46:50.473553Z","steps":["trace[159854833] 'process raft request' (duration: 122.096607ms)","trace[159854833] 'compare' (duration: 68.918065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:50.473878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.576865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-06-23T18:46:50.473958Z","caller":"traceutil/trace.go:171","msg":"trace[159522655] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5084; }","duration":"187.703806ms","start":"2026-06-23T18:46:50.286233Z","end":"2026-06-23T18:46:50.473937Z","steps":["trace[159522655] 'agreement among raft nodes before linearized reading' (duration: 187.498173ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:18.440798Z","caller":"traceutil/trace.go:171","msg":"trace[1826658738] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"155.337675ms","start":"2026-06-23T18:47:18.285431Z","end":"2026-06-23T18:47:18.440769Z","steps":["trace[1826658738] 'process raft request' (duration: 129.333624ms)","trace[1826658738] 'compare' (duration: 25.670246ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:18.440918Z","caller":"traceutil/trace.go:171","msg":"trace[2057671749] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"140.535196ms","start":"2026-06-23T18:47:18.30036Z","end":"2026-06-23T18:47:18.440895Z","steps":["trace[2057671749] 'process raft request' (duration: 140.336592ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:20.11563Z","caller":"traceutil/trace.go:171","msg":"trace[646767108] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"215.28306ms","start":"2026-06-23T18:47:19.900316Z","end":"2026-06-23T18:47:20.1156Z","steps":["trace[646767108] 'process raft request' (duration: 212.065129ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:20.169448Z","caller":"traceutil/trace.go:171","msg":"trace[31897293] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"182.952968ms","start":"2026-06-23T18:47:19.986445Z","end":"2026-06-23T18:47:20.169398Z","steps":["trace[31897293] 'process raft request' (duration: 182.773874ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:21.292079Z","caller":"traceutil/trace.go:171","msg":"trace[1701718794] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"167.466574ms","start":"2026-06-23T18:47:21.124594Z","end":"2026-06-23T18:47:21.292061Z","steps":["trace[1701718794] 'process raft request' (duration: 167.342832ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:21.463916Z","caller":"traceutil/trace.go:171","msg":"trace[471723997] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"163.379229ms","start":"2026-06-23T18:47:21.300501Z","end":"2026-06-23T18:47:21.46388Z","steps":["trace[471723997] 'process raft request' (duration: 96.809503ms)","trace[471723997] 'compare' (duration: 66.371473ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:34.180719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2825} {"level":"info","ts":"2026-06-23T18:47:34.265896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2825,"took":"83.150309ms","hash":65464031,"current-db-size-bytes":53587968,"current-db-size":"54 MB","current-db-size-in-use-bytes":28577792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-23T18:47:34.26597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":65464031,"revision":2825,"compact-revision":-1} {"level":"info","ts":"2026-06-23T18:52:34.187292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5438} {"level":"info","ts":"2026-06-23T18:52:34.270571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5438,"took":"81.156178ms","hash":833596328,"current-db-size-bytes":53587968,"current-db-size":"54 MB","current-db-size-in-use-bytes":18411520,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-23T18:52:34.270666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":833596328,"revision":5438,"compact-revision":2825}